{"level":"warn","ts":"2026-10-15T14:17:27.489534Z","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-15T14:17:27.489677Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.66: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.66:2380","--initial-cluster=instance=https://162.253.55.66:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.66:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.66: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-15T14:17:27.489798Z","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-15T14:17:27.48982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-15T14:17:27.489858Z","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-15T14:17:27.491059Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"]} {"level":"info","ts":"2026-10-15T14:17:27.491213Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.66: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-15T14:17:27.496444Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.812656ms"} {"level":"info","ts":"2026-10-15T14:17:27.50525Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"48afe2e91b68f008","cluster-id":"5894dcc7313657d8"} {"level":"info","ts":"2026-10-15T14:17:27.505384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:17:27.50541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 0"} {"level":"info","ts":"2026-10-15T14:17:27.505526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 48afe2e91b68f008 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:17:27.505552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 1"} {"level":"info","ts":"2026-10-15T14:17:27.5056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"warn","ts":"2026-10-15T14:17:27.515536Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:17:27.516999Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:17:27.518382Z","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-15T14:17:27.5216Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"48afe2e91b68f008","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:17:27.521719Z","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-15T14:17:27.521797Z","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-15T14:17:27.52181Z","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-15T14:17:27.521791Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"48afe2e91b68f008","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:17:27.524191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"info","ts":"2026-10-15T14:17:27.525813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","added-peer-id":"48afe2e91b68f008","added-peer-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-10-15T14:17:27.527947Z","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-15T14:17:27.528054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-15T14:17:27.528165Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-10-15T14:17:27.528933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"48afe2e91b68f008","initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:17:27.528986Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:17:28.206207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:17:28.206304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:17:28.206347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgPreVoteResp from 48afe2e91b68f008 at term 1"} {"level":"info","ts":"2026-10-15T14:17:28.20637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:17:28.206388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgVoteResp from 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-15T14:17:28.206413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became leader at term 2"} {"level":"info","ts":"2026-10-15T14:17:28.206431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 48afe2e91b68f008 elected leader 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-10-15T14:17:28.207892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"48afe2e91b68f008","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.66:2379]}","request-path":"/0/members/48afe2e91b68f008/attributes","cluster-id":"5894dcc7313657d8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:17:28.207942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:17:28.208177Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:28.208191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:17:28.20831Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:17:28.208349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:17:28.209748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:28.209903Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:28.20994Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:28.21182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.66:2379"} {"level":"info","ts":"2026-10-15T14:17:28.21188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-15T14:17:47.213829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.730761ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T14:18:20.399075Z","caller":"traceutil/trace.go:171","msg":"trace[358102324] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"156.298365ms","start":"2026-10-15T14:18:20.242744Z","end":"2026-10-15T14:18:20.399043Z","steps":["trace[358102324] 'process raft request' (duration: 155.729402ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:20.578324Z","caller":"traceutil/trace.go:171","msg":"trace[1110653239] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"131.120337ms","start":"2026-10-15T14:18:20.44718Z","end":"2026-10-15T14:18:20.5783Z","steps":["trace[1110653239] 'read index received' (duration: 83.974706ms)","trace[1110653239] 'applied index is now lower than readState.Index' (duration: 47.144691ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:20.578408Z","caller":"traceutil/trace.go:171","msg":"trace[625392258] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"175.529493ms","start":"2026-10-15T14:18:20.402871Z","end":"2026-10-15T14:18:20.578401Z","steps":["trace[625392258] 'process raft request' (duration: 128.190348ms)","trace[625392258] 'compare' (duration: 47.12739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:20.578608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.388874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dwlmt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-15T14:18:20.578693Z","caller":"traceutil/trace.go:171","msg":"trace[1620739321] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dwlmt; range_end:; response_count:1; response_revision:610; }","duration":"131.535597ms","start":"2026-10-15T14:18:20.447135Z","end":"2026-10-15T14:18:20.57867Z","steps":["trace[1620739321] 'agreement among raft nodes before linearized reading' (duration: 131.281681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:20.589066Z","caller":"traceutil/trace.go:171","msg":"trace[2096973161] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"112.891114ms","start":"2026-10-15T14:18:20.476151Z","end":"2026-10-15T14:18:20.589042Z","steps":["trace[2096973161] 'process raft request' (duration: 112.689229ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:21.061913Z","caller":"traceutil/trace.go:171","msg":"trace[746116562] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"461.49568ms","start":"2026-10-15T14:18:20.60038Z","end":"2026-10-15T14:18:21.061876Z","steps":["trace[746116562] 'process raft request' (duration: 409.668148ms)","trace[746116562] 'compare' (duration: 51.636698ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:21.066067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:20.600364Z","time spent":"461.636574ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:54.212677Z","caller":"traceutil/trace.go:171","msg":"trace[2058850085] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"249.797158ms","start":"2026-10-15T14:19:53.962857Z","end":"2026-10-15T14:19:54.212655Z","steps":["trace[2058850085] 'process raft request' (duration: 249.659574ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:54.222811Z","caller":"traceutil/trace.go:171","msg":"trace[1527528961] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"157.195142ms","start":"2026-10-15T14:19:54.065588Z","end":"2026-10-15T14:19:54.222783Z","steps":["trace[1527528961] 'process raft request' (duration: 156.985407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:14.057089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.998981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:27:14.0572Z","caller":"traceutil/trace.go:171","msg":"trace[1404541733] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5559; }","duration":"371.41517ms","start":"2026-10-15T14:27:13.685768Z","end":"2026-10-15T14:27:14.057183Z","steps":["trace[1404541733] 'read index received' (duration: 135.178663ms)","trace[1404541733] 'applied index is now lower than readState.Index' (duration: 236.235117ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:14.057365Z","caller":"traceutil/trace.go:171","msg":"trace[1773980057] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"392.060368ms","start":"2026-10-15T14:27:13.665244Z","end":"2026-10-15T14:27:14.057305Z","steps":["trace[1773980057] 'process raft request' (duration: 155.77402ms)","trace[1773980057] 'compare' (duration: 235.726124ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:27:14.057578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:13.665225Z","time spent":"392.243692ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:27:14.057568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.774549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:27:14.057681Z","caller":"traceutil/trace.go:171","msg":"trace[2070366944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5359; }","duration":"371.950603ms","start":"2026-10-15T14:27:13.685705Z","end":"2026-10-15T14:27:14.057655Z","steps":["trace[2070366944] 'agreement among raft nodes before linearized reading' (duration: 371.633675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:14.057751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:13.685681Z","time spent":"372.056656ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:27:14.099182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.198612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:27:14.099283Z","caller":"traceutil/trace.go:171","msg":"trace[550569075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5360; }","duration":"152.366946ms","start":"2026-10-15T14:27:13.946892Z","end":"2026-10-15T14:27:14.099259Z","steps":["trace[550569075] 'agreement among raft nodes before linearized reading' (duration: 152.181901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:14.099347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.905718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786897"} {"level":"info","ts":"2026-10-15T14:27:14.099422Z","caller":"traceutil/trace.go:171","msg":"trace[939612944] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5360; }","duration":"299.038861ms","start":"2026-10-15T14:27:13.800367Z","end":"2026-10-15T14:27:14.099406Z","steps":["trace[939612944] 'agreement among raft nodes before linearized reading' (duration: 298.421946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:14.42261Z","caller":"traceutil/trace.go:171","msg":"trace[810340547] linearizableReadLoop","detail":"{readStateIndex:5565; appliedIndex:5563; }","duration":"134.386083ms","start":"2026-10-15T14:27:14.288192Z","end":"2026-10-15T14:27:14.422578Z","steps":["trace[810340547] 'read index received' (duration: 70.311868ms)","trace[810340547] 'applied index is now lower than readState.Index' (duration: 64.072765ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:14.4228Z","caller":"traceutil/trace.go:171","msg":"trace[1752194858] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"171.66022ms","start":"2026-10-15T14:27:14.251109Z","end":"2026-10-15T14:27:14.422769Z","steps":["trace[1752194858] 'process raft request' (duration: 107.38641ms)","trace[1752194858] 'compare' (duration: 63.834559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:27:14.422886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.6446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-804ce035-bb51-46e8-9e49-e631e73de32f\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-15T14:27:14.422936Z","caller":"traceutil/trace.go:171","msg":"trace[1657077637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-804ce035-bb51-46e8-9e49-e631e73de32f; range_end:; response_count:1; response_revision:5364; }","duration":"134.781523ms","start":"2026-10-15T14:27:14.288139Z","end":"2026-10-15T14:27:14.422921Z","steps":["trace[1657077637] 'agreement among raft nodes before linearized reading' (duration: 134.550177ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:14.422963Z","caller":"traceutil/trace.go:171","msg":"trace[1645087159] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"166.124764ms","start":"2026-10-15T14:27:14.256819Z","end":"2026-10-15T14:27:14.422944Z","steps":["trace[1645087159] 'process raft request' (duration: 165.683053ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:28.326509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2933} {"level":"warn","ts":"2026-10-15T14:27:28.537621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.652169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:27:28.538217Z","caller":"traceutil/trace.go:171","msg":"trace[1603464269] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"145.952858ms","start":"2026-10-15T14:27:28.392236Z","end":"2026-10-15T14:27:28.538188Z","steps":["trace[1603464269] 'process raft request' (duration: 41.667785ms)","trace[1603464269] 'compare' (duration: 65.267814ms)","trace[1603464269] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/monitoring/goldpinger-2pkbj.18deba374b5186a9; req_size:713; } (duration: 38.250961ms)"],"step_count":3} {"level":"info","ts":"2026-10-15T14:27:28.884334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2933,"took":"556.395607ms","hash":4276499738,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:27:28.884392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4276499738,"revision":2933,"compact-revision":-1} {"level":"warn","ts":"2026-10-15T14:27:28.884764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.89773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:27:28.884856Z","caller":"traceutil/trace.go:171","msg":"trace[703773712] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"265.916187ms","start":"2026-10-15T14:27:28.618926Z","end":"2026-10-15T14:27:28.884842Z","steps":["trace[703773712] 'process raft request' (duration: 20.867523ms)","trace[703773712] 'compare' (duration: 244.704105ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:28.932493Z","caller":"traceutil/trace.go:171","msg":"trace[1346078351] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"313.323453ms","start":"2026-10-15T14:27:28.619151Z","end":"2026-10-15T14:27:28.932475Z","steps":["trace[1346078351] 'process raft request' (duration: 313.182139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:28.932606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:28.619125Z","time spent":"313.428905ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:27:28.932753Z","caller":"traceutil/trace.go:171","msg":"trace[1992146325] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"312.867241ms","start":"2026-10-15T14:27:28.619864Z","end":"2026-10-15T14:27:28.932731Z","steps":["trace[1992146325] 'process raft request' (duration: 312.571214ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:28.9328Z","caller":"traceutil/trace.go:171","msg":"trace[1833230910] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"312.906962ms","start":"2026-10-15T14:27:28.619878Z","end":"2026-10-15T14:27:28.932785Z","steps":["trace[1833230910] 'process raft request' (duration: 312.788689ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:28.932864Z","caller":"traceutil/trace.go:171","msg":"trace[5466138] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"306.429643ms","start":"2026-10-15T14:27:28.626426Z","end":"2026-10-15T14:27:28.932855Z","steps":["trace[5466138] 'process raft request' (duration: 306.32507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:28.932873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:28.619864Z","time spent":"312.973114ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:27:28.932873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:28.619852Z","time spent":"312.952313ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:27:28.932919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:27:28.626409Z","time spent":"306.473624ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:27:28.965344Z","caller":"traceutil/trace.go:171","msg":"trace[583784182] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"169.516007ms","start":"2026-10-15T14:27:28.795804Z","end":"2026-10-15T14:27:28.96532Z","steps":["trace[583784182] 'process raft request' (duration: 169.364824ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:36.350076Z","caller":"traceutil/trace.go:171","msg":"trace[406923250] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"108.280812ms","start":"2026-10-15T14:27:36.241766Z","end":"2026-10-15T14:27:36.350047Z","steps":["trace[406923250] 'process raft request' (duration: 23.670282ms)","trace[406923250] 'compare' (duration: 84.098768ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:36.535908Z","caller":"traceutil/trace.go:171","msg":"trace[126250582] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"163.225792ms","start":"2026-10-15T14:27:36.372648Z","end":"2026-10-15T14:27:36.535874Z","steps":["trace[126250582] 'process raft request' (duration: 160.242729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:36.586238Z","caller":"traceutil/trace.go:171","msg":"trace[1659762368] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"157.406379ms","start":"2026-10-15T14:27:36.428796Z","end":"2026-10-15T14:27:36.586203Z","steps":["trace[1659762368] 'process raft request' (duration: 156.999249ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:36.586231Z","caller":"traceutil/trace.go:171","msg":"trace[284116714] linearizableReadLoop","detail":"{readStateIndex:6009; appliedIndex:6007; }","duration":"156.840316ms","start":"2026-10-15T14:27:36.429307Z","end":"2026-10-15T14:27:36.586147Z","steps":["trace[284116714] 'read index received' (duration: 103.602647ms)","trace[284116714] 'applied index is now lower than readState.Index' (duration: 53.235939ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:27:36.586378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.067941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:27:36.586427Z","caller":"traceutil/trace.go:171","msg":"trace[1167578913] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5795; }","duration":"157.182233ms","start":"2026-10-15T14:27:36.429231Z","end":"2026-10-15T14:27:36.586413Z","steps":["trace[1167578913] 'agreement among raft nodes before linearized reading' (duration: 157.04634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:36.743696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.841208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:27:36.743852Z","caller":"traceutil/trace.go:171","msg":"trace[583920837] linearizableReadLoop","detail":"{readStateIndex:6010; appliedIndex:6009; }","duration":"157.46605ms","start":"2026-10-15T14:27:36.586343Z","end":"2026-10-15T14:27:36.743809Z","steps":["trace[583920837] 'read index received' (duration: 56.341474ms)","trace[583920837] 'applied index is now lower than readState.Index' (duration: 101.123046ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:36.744121Z","caller":"traceutil/trace.go:171","msg":"trace[874112111] transaction","detail":"{read_only:false; response_revision:5796; number_of_response:1; }","duration":"176.721334ms","start":"2026-10-15T14:27:36.567372Z","end":"2026-10-15T14:27:36.744093Z","steps":["trace[874112111] 'process raft request' (duration: 75.393393ms)","trace[874112111] 'compare' (duration: 100.706615ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:27:36.744149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.712801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-10-15T14:27:36.744165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.635021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:27:36.744202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.946881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18deba38e552a2a5\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-10-15T14:27:36.744225Z","caller":"traceutil/trace.go:171","msg":"trace[58291782] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5796; }","duration":"158.827323ms","start":"2026-10-15T14:27:36.58538Z","end":"2026-10-15T14:27:36.744207Z","steps":["trace[58291782] 'agreement among raft nodes before linearized reading' (duration: 158.574787ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:36.744251Z","caller":"traceutil/trace.go:171","msg":"trace[543215732] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5796; }","duration":"154.748203ms","start":"2026-10-15T14:27:36.589478Z","end":"2026-10-15T14:27:36.744226Z","steps":["trace[543215732] 'agreement among raft nodes before linearized reading' (duration: 154.592829ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:36.744282Z","caller":"traceutil/trace.go:171","msg":"trace[1642465515] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18deba38e552a2a5; range_end:; response_count:1; response_revision:5796; }","duration":"280.072744ms","start":"2026-10-15T14:27:36.464184Z","end":"2026-10-15T14:27:36.744257Z","steps":["trace[1642465515] 'agreement among raft nodes before linearized reading' (duration: 279.856928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:36.744318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.938414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19cb85a7-319e-48ce-96f6-9758771d33e7\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-15T14:27:36.744358Z","caller":"traceutil/trace.go:171","msg":"trace[96638557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19cb85a7-319e-48ce-96f6-9758771d33e7; range_end:; response_count:1; response_revision:5796; }","duration":"165.016436ms","start":"2026-10-15T14:27:36.579329Z","end":"2026-10-15T14:27:36.744345Z","steps":["trace[96638557] 'agreement among raft nodes before linearized reading' (duration: 164.597996ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:28.333937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5617} {"level":"info","ts":"2026-10-15T14:32:28.420477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5617,"took":"84.775838ms","hash":2388765965,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":26644480,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T14:32:28.420556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2388765965,"revision":5617,"compact-revision":2933} {"level":"warn","ts":"2026-10-15T14:33:47.122776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.188239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:33:47.122959Z","caller":"traceutil/trace.go:171","msg":"trace[466424720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8639; }","duration":"175.454734ms","start":"2026-10-15T14:33:46.947475Z","end":"2026-10-15T14:33:47.122929Z","steps":["trace[466424720] 'range keys from in-memory index tree' (duration: 175.004494ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:57.953541Z","caller":"traceutil/trace.go:171","msg":"trace[1749675896] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"134.171802ms","start":"2026-10-15T14:33:57.819336Z","end":"2026-10-15T14:33:57.953507Z","steps":["trace[1749675896] 'process raft request' (duration: 107.78678ms)","trace[1749675896] 'compare' (duration: 26.259859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:58.380338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.08757ms","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-10-15T14:33:58.380425Z","caller":"traceutil/trace.go:171","msg":"trace[1707559937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8697; }","duration":"134.224313ms","start":"2026-10-15T14:33:58.246179Z","end":"2026-10-15T14:33:58.380404Z","steps":["trace[1707559937] 'range keys from in-memory index tree' (duration: 133.853575ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:58.616457Z","caller":"traceutil/trace.go:171","msg":"trace[1047868234] linearizableReadLoop","detail":"{readStateIndex:9004; appliedIndex:9003; }","duration":"155.403483ms","start":"2026-10-15T14:33:58.461027Z","end":"2026-10-15T14:33:58.61643Z","steps":["trace[1047868234] 'read index received' (duration: 155.324801ms)","trace[1047868234] 'applied index is now lower than readState.Index' (duration: 77.262µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:58.616498Z","caller":"traceutil/trace.go:171","msg":"trace[1227516784] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"231.205629ms","start":"2026-10-15T14:33:58.385262Z","end":"2026-10-15T14:33:58.616467Z","steps":["trace[1227516784] 'process raft request' (duration: 230.989023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:58.61667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.617408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:33:58.616714Z","caller":"traceutil/trace.go:171","msg":"trace[848318529] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8698; }","duration":"155.71676ms","start":"2026-10-15T14:33:58.460984Z","end":"2026-10-15T14:33:58.616701Z","steps":["trace[848318529] 'agreement among raft nodes before linearized reading' (duration: 155.546706ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:58.665754Z","caller":"traceutil/trace.go:171","msg":"trace[1196014188] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"138.953974ms","start":"2026-10-15T14:33:58.526776Z","end":"2026-10-15T14:33:58.66573Z","steps":["trace[1196014188] 'process raft request' (duration: 138.806791ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:58.792115Z","caller":"traceutil/trace.go:171","msg":"trace[988989885] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"122.030036ms","start":"2026-10-15T14:33:58.670052Z","end":"2026-10-15T14:33:58.792082Z","steps":["trace[988989885] 'process raft request' (duration: 68.388712ms)","trace[988989885] 'compare' (duration: 53.510271ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:59.773189Z","caller":"traceutil/trace.go:171","msg":"trace[782874644] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"108.214469ms","start":"2026-10-15T14:33:59.664947Z","end":"2026-10-15T14:33:59.773161Z","steps":["trace[782874644] 'process raft request' (duration: 107.964003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:34:00.205957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.261317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:34:00.206061Z","caller":"traceutil/trace.go:171","msg":"trace[1310886512] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8706; }","duration":"132.426701ms","start":"2026-10-15T14:34:00.073592Z","end":"2026-10-15T14:34:00.206019Z","steps":["trace[1310886512] 'count revisions from in-memory index tree' (duration: 132.118003ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:28.956409Z","caller":"traceutil/trace.go:171","msg":"trace[323659832] linearizableReadLoop","detail":"{readStateIndex:9227; appliedIndex:9226; }","duration":"121.813723ms","start":"2026-10-15T14:34:28.83457Z","end":"2026-10-15T14:34:28.956384Z","steps":["trace[323659832] 'read index received' (duration: 121.630098ms)","trace[323659832] 'applied index is now lower than readState.Index' (duration: 182.695µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:28.956462Z","caller":"traceutil/trace.go:171","msg":"trace[1537681558] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"156.012108ms","start":"2026-10-15T14:34:28.800418Z","end":"2026-10-15T14:34:28.95643Z","steps":["trace[1537681558] 'process raft request' (duration: 155.829773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:34:28.956645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.056278ms","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-10-15T14:34:28.956688Z","caller":"traceutil/trace.go:171","msg":"trace[1805918652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8913; }","duration":"122.349756ms","start":"2026-10-15T14:34:28.834327Z","end":"2026-10-15T14:34:28.956677Z","steps":["trace[1805918652] 'agreement among raft nodes before linearized reading' (duration: 122.153171ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:31.073213Z","caller":"traceutil/trace.go:171","msg":"trace[62948930] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"111.484646ms","start":"2026-10-15T14:34:30.961706Z","end":"2026-10-15T14:34:31.073191Z","steps":["trace[62948930] 'process raft request' (duration: 111.200299ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:31.200155Z","caller":"traceutil/trace.go:171","msg":"trace[2068672552] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"121.527996ms","start":"2026-10-15T14:34:31.078583Z","end":"2026-10-15T14:34:31.200111Z","steps":["trace[2068672552] 'process raft request' (duration: 121.30256ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:38.197626Z","caller":"traceutil/trace.go:171","msg":"trace[1331619544] transaction","detail":"{read_only:false; response_revision:8962; number_of_response:1; }","duration":"113.519215ms","start":"2026-10-15T14:34:38.084075Z","end":"2026-10-15T14:34:38.197595Z","steps":["trace[1331619544] 'process raft request' (duration: 113.170957ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:38.359426Z","caller":"traceutil/trace.go:171","msg":"trace[359932753] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"157.706388ms","start":"2026-10-15T14:34:38.201695Z","end":"2026-10-15T14:34:38.359401Z","steps":["trace[359932753] 'process raft request' (duration: 126.70815ms)","trace[359932753] 'compare' (duration: 30.713541ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:38.359573Z","caller":"traceutil/trace.go:171","msg":"trace[1406893184] linearizableReadLoop","detail":"{readStateIndex:9279; appliedIndex:9278; }","duration":"121.28465ms","start":"2026-10-15T14:34:38.238233Z","end":"2026-10-15T14:34:38.359518Z","steps":["trace[1406893184] 'read index received' (duration: 90.22558ms)","trace[1406893184] 'applied index is now lower than readState.Index' (duration: 31.05554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:34:38.359776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.507096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mm8k8\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:34:38.359843Z","caller":"traceutil/trace.go:171","msg":"trace[1971233514] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mm8k8; range_end:; response_count:1; response_revision:8963; }","duration":"121.648439ms","start":"2026-10-15T14:34:38.238178Z","end":"2026-10-15T14:34:38.359826Z","steps":["trace[1971233514] 'agreement among raft nodes before linearized reading' (duration: 121.429114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:41.932835Z","caller":"traceutil/trace.go:171","msg":"trace[130537116] linearizableReadLoop","detail":"{readStateIndex:9309; appliedIndex:9308; }","duration":"122.081099ms","start":"2026-10-15T14:34:41.810727Z","end":"2026-10-15T14:34:41.932808Z","steps":["trace[130537116] 'read index received' (duration: 103.971697ms)","trace[130537116] 'applied index is now lower than readState.Index' (duration: 18.107222ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:34:41.933044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.313095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bxbzx\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-10-15T14:34:41.933082Z","caller":"traceutil/trace.go:171","msg":"trace[44263564] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bxbzx; range_end:; response_count:1; response_revision:8992; }","duration":"122.391146ms","start":"2026-10-15T14:34:41.81068Z","end":"2026-10-15T14:34:41.933072Z","steps":["trace[44263564] 'agreement among raft nodes before linearized reading' (duration: 122.218202ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:42.086182Z","caller":"traceutil/trace.go:171","msg":"trace[1245476999] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"149.949743ms","start":"2026-10-15T14:34:41.936208Z","end":"2026-10-15T14:34:42.086157Z","steps":["trace[1245476999] 'process raft request' (duration: 149.886981ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:42.086497Z","caller":"traceutil/trace.go:171","msg":"trace[1975695350] linearizableReadLoop","detail":"{readStateIndex:9310; appliedIndex:9309; }","duration":"150.320312ms","start":"2026-10-15T14:34:41.936149Z","end":"2026-10-15T14:34:42.086469Z","steps":["trace[1975695350] 'read index received' (duration: 111.096598ms)","trace[1975695350] 'applied index is now lower than readState.Index' (duration: 39.220354ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:34:42.086695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.052674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:34:42.086745Z","caller":"traceutil/trace.go:171","msg":"trace[2107323182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8994; }","duration":"139.135026ms","start":"2026-10-15T14:34:41.947598Z","end":"2026-10-15T14:34:42.086733Z","steps":["trace[2107323182] 'agreement among raft nodes before linearized reading' (duration: 139.047104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:34:42.086786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.615499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bxbzx\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-10-15T14:34:42.086832Z","caller":"traceutil/trace.go:171","msg":"trace[320167878] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bxbzx; range_end:; response_count:1; response_revision:8994; }","duration":"150.698501ms","start":"2026-10-15T14:34:41.936122Z","end":"2026-10-15T14:34:42.08682Z","steps":["trace[320167878] 'agreement among raft nodes before linearized reading' (duration: 150.519047ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:42.086648Z","caller":"traceutil/trace.go:171","msg":"trace[1280365512] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"152.33238ms","start":"2026-10-15T14:34:41.934285Z","end":"2026-10-15T14:34:42.086618Z","steps":["trace[1280365512] 'process raft request' (duration: 112.933201ms)","trace[1280365512] 'compare' (duration: 38.673762ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:58.223176Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000250a.wal"} {"level":"info","ts":"2026-10-15T14:35:44.940399Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"48afe2e91b68f008","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:35:44.949703Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:35:44.949818Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:36:07.683196Z","caller":"traceutil/trace.go:171","msg":"trace[315516630] transaction","detail":"{read_only:false; response_revision:9819; number_of_response:1; }","duration":"207.970215ms","start":"2026-10-15T14:36:07.475181Z","end":"2026-10-15T14:36:07.683151Z","steps":["trace[315516630] 'process raft request' (duration: 207.73253ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:37:28.345816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7993} {"level":"info","ts":"2026-10-15T14:37:28.435985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7993,"took":"87.988694ms","hash":3472752612,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":25284608,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T14:37:28.43606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472752612,"revision":7993,"compact-revision":5617} {"level":"warn","ts":"2026-10-15T14:41:47.435569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.187653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:41:47.435665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.235364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:41:47.435777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.86284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:47.435822Z","caller":"traceutil/trace.go:171","msg":"trace[1603420826] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12257; }","duration":"200.929391ms","start":"2026-10-15T14:41:47.234878Z","end":"2026-10-15T14:41:47.435807Z","steps":["trace[1603420826] 'range keys from in-memory index tree' (duration: 200.786478ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:47.43582Z","caller":"traceutil/trace.go:171","msg":"trace[704695579] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12257; }","duration":"200.439749ms","start":"2026-10-15T14:41:47.235362Z","end":"2026-10-15T14:41:47.435802Z","steps":["trace[704695579] 'range keys from in-memory index tree' (duration: 200.180742ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:47.435693Z","caller":"traceutil/trace.go:171","msg":"trace[1538049295] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12257; }","duration":"200.377887ms","start":"2026-10-15T14:41:47.235289Z","end":"2026-10-15T14:41:47.435667Z","steps":["trace[1538049295] 'range keys from in-memory index tree' (duration: 200.082621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.436004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.791538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:41:47.436068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.20128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-15T14:41:47.436126Z","caller":"traceutil/trace.go:171","msg":"trace[353131446] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12257; }","duration":"200.921831ms","start":"2026-10-15T14:41:47.235135Z","end":"2026-10-15T14:41:47.436057Z","steps":["trace[353131446] 'range keys from in-memory index tree' (duration: 200.723246ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:47.436151Z","caller":"traceutil/trace.go:171","msg":"trace[1124215138] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12257; }","duration":"197.383285ms","start":"2026-10-15T14:41:47.238751Z","end":"2026-10-15T14:41:47.436134Z","steps":["trace[1124215138] 'range keys from in-memory index tree' (duration: 196.983555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.436746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.872224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T14:41:47.436839Z","caller":"traceutil/trace.go:171","msg":"trace[1115550994] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12257; }","duration":"202.004647ms","start":"2026-10-15T14:41:47.234821Z","end":"2026-10-15T14:41:47.436825Z","steps":["trace[1115550994] 'range keys from in-memory index tree' (duration: 200.981912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.497871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.701209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T14:41:47.498021Z","caller":"traceutil/trace.go:171","msg":"trace[696583113] linearizableReadLoop","detail":"{readStateIndex:12676; appliedIndex:12675; }","duration":"120.236937ms","start":"2026-10-15T14:41:47.377761Z","end":"2026-10-15T14:41:47.497998Z","steps":["trace[696583113] 'read index received' (duration: 35.961µs)","trace[696583113] 'applied index is now lower than readState.Index' (duration: 120.198865ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:41:47.498124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.349689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:47.498199Z","caller":"traceutil/trace.go:171","msg":"trace[669343064] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12257; }","duration":"120.460642ms","start":"2026-10-15T14:41:47.377724Z","end":"2026-10-15T14:41:47.498185Z","steps":["trace[669343064] 'agreement among raft nodes before linearized reading' (duration: 120.349429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.498157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.887048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:47.498279Z","caller":"traceutil/trace.go:171","msg":"trace[1404945002] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12257; }","duration":"120.041642ms","start":"2026-10-15T14:41:47.378228Z","end":"2026-10-15T14:41:47.49827Z","steps":["trace[1404945002] 'agreement among raft nodes before linearized reading' (duration: 119.881288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.49829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.591076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-15T14:41:47.498332Z","caller":"traceutil/trace.go:171","msg":"trace[1266396835] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12257; }","duration":"118.652688ms","start":"2026-10-15T14:41:47.379668Z","end":"2026-10-15T14:41:47.498321Z","steps":["trace[1266396835] 'agreement among raft nodes before linearized reading' (duration: 118.464034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.498329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.845178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:41:47.498353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.025691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:47.498426Z","caller":"traceutil/trace.go:171","msg":"trace[31867977] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12257; }","duration":"119.96868ms","start":"2026-10-15T14:41:47.378434Z","end":"2026-10-15T14:41:47.498403Z","steps":["trace[31867977] 'agreement among raft nodes before linearized reading' (duration: 119.764555ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:47.498431Z","caller":"traceutil/trace.go:171","msg":"trace[1541700640] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12257; }","duration":"120.136375ms","start":"2026-10-15T14:41:47.378277Z","end":"2026-10-15T14:41:47.498413Z","steps":["trace[1541700640] 'agreement among raft nodes before linearized reading' (duration: 119.978571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:47.498498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.677391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mm8k8\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:41:47.49853Z","caller":"traceutil/trace.go:171","msg":"trace[22162388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mm8k8; range_end:; response_count:1; response_revision:12257; }","duration":"107.729482ms","start":"2026-10-15T14:41:47.390791Z","end":"2026-10-15T14:41:47.498521Z","steps":["trace[22162388] 'agreement among raft nodes before linearized reading' (duration: 107.63822ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:28.352942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10381} {"level":"info","ts":"2026-10-15T14:42:28.458335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10381,"took":"100.952157ms","hash":4108278627,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":25788416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:42:28.458412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4108278627,"revision":10381,"compact-revision":7993} {"level":"info","ts":"2026-10-15T14:47:28.361677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12552} {"level":"info","ts":"2026-10-15T14:47:28.446769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12552,"took":"82.509904ms","hash":1394650167,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":27918336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:47:28.446904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1394650167,"revision":12552,"compact-revision":10381} {"level":"info","ts":"2026-10-15T14:47:32.616866Z","caller":"traceutil/trace.go:171","msg":"trace[2144717663] linearizableReadLoop","detail":"{readStateIndex:15133; appliedIndex:15132; }","duration":"132.30754ms","start":"2026-10-15T14:47:32.48449Z","end":"2026-10-15T14:47:32.616797Z","steps":["trace[2144717663] 'read index received' (duration: 132.060885ms)","trace[2144717663] 'applied index is now lower than readState.Index' (duration: 245.515µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:47:32.616912Z","caller":"traceutil/trace.go:171","msg":"trace[984482557] transaction","detail":"{read_only:false; response_revision:14631; number_of_response:1; }","duration":"144.594343ms","start":"2026-10-15T14:47:32.472283Z","end":"2026-10-15T14:47:32.616877Z","steps":["trace[984482557] 'process raft request' (duration: 144.315917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:32.617237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.73386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mm8k8\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:47:32.617298Z","caller":"traceutil/trace.go:171","msg":"trace[434000206] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mm8k8; range_end:; response_count:1; response_revision:14631; }","duration":"132.809712ms","start":"2026-10-15T14:47:32.484477Z","end":"2026-10-15T14:47:32.617286Z","steps":["trace[434000206] 'agreement among raft nodes before linearized reading' (duration: 132.608097ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:55.881921Z","caller":"traceutil/trace.go:171","msg":"trace[986220272] transaction","detail":"{read_only:false; response_revision:14752; number_of_response:1; }","duration":"134.834621ms","start":"2026-10-15T14:47:55.747066Z","end":"2026-10-15T14:47:55.8819Z","steps":["trace[986220272] 'process raft request' (duration: 134.715448ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:55.992933Z","caller":"traceutil/trace.go:171","msg":"trace[168929093] transaction","detail":"{read_only:false; response_revision:14753; number_of_response:1; }","duration":"245.444454ms","start":"2026-10-15T14:47:55.747447Z","end":"2026-10-15T14:47:55.992892Z","steps":["trace[168929093] 'process raft request' (duration: 245.227039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:04.555472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.508401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:48:04.555568Z","caller":"traceutil/trace.go:171","msg":"trace[1820478890] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"140.581078ms","start":"2026-10-15T14:48:04.414968Z","end":"2026-10-15T14:48:04.555549Z","steps":["trace[1820478890] 'process raft request' (duration: 37.916393ms)","trace[1820478890] 'compare' (duration: 102.255015ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:48:12.862159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.157802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-15T14:48:12.862275Z","caller":"traceutil/trace.go:171","msg":"trace[2044522283] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14857; }","duration":"123.323756ms","start":"2026-10-15T14:48:12.738924Z","end":"2026-10-15T14:48:12.862248Z","steps":["trace[2044522283] 'range keys from in-memory index tree' (duration: 122.942668ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:13.571481Z","caller":"traceutil/trace.go:171","msg":"trace[2068491995] transaction","detail":"{read_only:false; response_revision:14860; number_of_response:1; }","duration":"215.610004ms","start":"2026-10-15T14:48:13.35584Z","end":"2026-10-15T14:48:13.57145Z","steps":["trace[2068491995] 'process raft request' (duration: 215.116522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:36.624079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.676022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-nova-server-0.18debb5e4eae5eca\" ","response":"range_response_count:1 size:1001"} {"level":"warn","ts":"2026-10-15T14:48:36.624174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.562012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8628e9eb-06ad-4c54-94a8-3884df13934b\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-10-15T14:48:36.624199Z","caller":"traceutil/trace.go:171","msg":"trace[812891044] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-nova-server-0.18debb5e4eae5eca; range_end:; response_count:1; response_revision:15170; }","duration":"104.813735ms","start":"2026-10-15T14:48:36.519359Z","end":"2026-10-15T14:48:36.624173Z","steps":["trace[812891044] 'range keys from in-memory index tree' (duration: 104.464897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:36.624205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.82997ms","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-15T14:48:36.624323Z","caller":"traceutil/trace.go:171","msg":"trace[1410374916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15170; }","duration":"106.012194ms","start":"2026-10-15T14:48:36.518285Z","end":"2026-10-15T14:48:36.624298Z","steps":["trace[1410374916] 'range keys from in-memory index tree' (duration: 105.606015ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:36.624254Z","caller":"traceutil/trace.go:171","msg":"trace[1796074149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8628e9eb-06ad-4c54-94a8-3884df13934b; range_end:; response_count:1; response_revision:15170; }","duration":"136.714236ms","start":"2026-10-15T14:48:36.487519Z","end":"2026-10-15T14:48:36.624233Z","steps":["trace[1796074149] 'range keys from in-memory index tree' (duration: 136.361926ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:36.768777Z","caller":"traceutil/trace.go:171","msg":"trace[398631417] transaction","detail":"{read_only:false; response_revision:15171; number_of_response:1; }","duration":"142.066772ms","start":"2026-10-15T14:48:36.626684Z","end":"2026-10-15T14:48:36.768751Z","steps":["trace[398631417] 'process raft request' (duration: 141.914228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:36.941653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.203981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:48:36.941909Z","caller":"traceutil/trace.go:171","msg":"trace[267050358] transaction","detail":"{read_only:false; response_revision:15173; number_of_response:1; }","duration":"260.597025ms","start":"2026-10-15T14:48:36.68129Z","end":"2026-10-15T14:48:36.941887Z","steps":["trace[267050358] 'process raft request' (duration: 260.480232ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:36.941942Z","caller":"traceutil/trace.go:171","msg":"trace[1990559946] transaction","detail":"{read_only:false; response_revision:15172; number_of_response:1; }","duration":"313.422402ms","start":"2026-10-15T14:48:36.628483Z","end":"2026-10-15T14:48:36.941906Z","steps":["trace[1990559946] 'process raft request' (duration: 208.859463ms)","trace[1990559946] 'compare' (duration: 104.039147ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:48:36.942259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:48:36.62846Z","time spent":"313.70442ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:49:53.041087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.70953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8t25w\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T14:49:53.041173Z","caller":"traceutil/trace.go:171","msg":"trace[350963915] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8t25w; range_end:; response_count:1; response_revision:15930; }","duration":"114.843183ms","start":"2026-10-15T14:49:52.926311Z","end":"2026-10-15T14:49:53.041154Z","steps":["trace[350963915] 'range keys from in-memory index tree' (duration: 114.492015ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:53.374873Z","caller":"traceutil/trace.go:171","msg":"trace[797738043] linearizableReadLoop","detail":"{readStateIndex:16477; appliedIndex:16476; }","duration":"105.43113ms","start":"2026-10-15T14:49:53.26941Z","end":"2026-10-15T14:49:53.374841Z","steps":["trace[797738043] 'read index received' (duration: 105.126082ms)","trace[797738043] 'applied index is now lower than readState.Index' (duration: 303.307µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:53.37498Z","caller":"traceutil/trace.go:171","msg":"trace[1900485563] transaction","detail":"{read_only:false; response_revision:15931; number_of_response:1; }","duration":"116.330489ms","start":"2026-10-15T14:49:53.258615Z","end":"2026-10-15T14:49:53.374946Z","steps":["trace[1900485563] 'process raft request' (duration: 116.030342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:53.375105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.668045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T14:49:53.375159Z","caller":"traceutil/trace.go:171","msg":"trace[524085444] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15931; }","duration":"105.769597ms","start":"2026-10-15T14:49:53.269375Z","end":"2026-10-15T14:49:53.375144Z","steps":["trace[524085444] 'agreement among raft nodes before linearized reading' (duration: 105.583062ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:28.367993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14602} {"level":"info","ts":"2026-10-15T14:52:28.449892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14602,"took":"79.377905ms","hash":1573566981,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":32026624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:52:28.449957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1573566981,"revision":14602,"compact-revision":12552} {"level":"info","ts":"2026-10-15T14:52:57.636296Z","caller":"traceutil/trace.go:171","msg":"trace[1044902546] transaction","detail":"{read_only:false; response_revision:17408; number_of_response:1; }","duration":"104.837323ms","start":"2026-10-15T14:52:57.531432Z","end":"2026-10-15T14:52:57.636269Z","steps":["trace[1044902546] 'process raft request' (duration: 104.664289ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:52.390264Z","caller":"traceutil/trace.go:171","msg":"trace[1523578409] linearizableReadLoop","detail":"{readStateIndex:19207; appliedIndex:19206; }","duration":"154.833793ms","start":"2026-10-15T14:55:52.23541Z","end":"2026-10-15T14:55:52.390244Z","steps":["trace[1523578409] 'read index received' (duration: 154.678719ms)","trace[1523578409] 'applied index is now lower than readState.Index' (duration: 153.814µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:52.390299Z","caller":"traceutil/trace.go:171","msg":"trace[1450043874] transaction","detail":"{read_only:false; response_revision:18574; number_of_response:1; }","duration":"187.889201ms","start":"2026-10-15T14:55:52.202388Z","end":"2026-10-15T14:55:52.390278Z","steps":["trace[1450043874] 'process raft request' (duration: 187.715587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:52.390502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.076058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:55:52.390537Z","caller":"traceutil/trace.go:171","msg":"trace[679774862] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18574; }","duration":"155.145389ms","start":"2026-10-15T14:55:52.235379Z","end":"2026-10-15T14:55:52.390525Z","steps":["trace[679774862] 'agreement among raft nodes before linearized reading' (duration: 154.979195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:56:00.210802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.595986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:56:00.2109Z","caller":"traceutil/trace.go:171","msg":"trace[51829541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18617; }","duration":"263.7474ms","start":"2026-10-15T14:55:59.94713Z","end":"2026-10-15T14:56:00.210877Z","steps":["trace[51829541] 'range keys from in-memory index tree' (duration: 263.530735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:56:00.211054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.674564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T14:56:00.21114Z","caller":"traceutil/trace.go:171","msg":"trace[476930162] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18617; }","duration":"410.817228ms","start":"2026-10-15T14:55:59.8003Z","end":"2026-10-15T14:56:00.211117Z","steps":["trace[476930162] 'range keys from in-memory index tree' (duration: 410.48746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:56:00.211182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:59.800281Z","time spent":"410.89127ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T14:56:00.211502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.813361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8t25w\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-15T14:56:00.211559Z","caller":"traceutil/trace.go:171","msg":"trace[1914560974] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8t25w; range_end:; response_count:1; response_revision:18617; }","duration":"289.906794ms","start":"2026-10-15T14:55:59.921638Z","end":"2026-10-15T14:56:00.211545Z","steps":["trace[1914560974] 'range keys from in-memory index tree' (duration: 289.595557ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:56:32.942558Z","caller":"traceutil/trace.go:171","msg":"trace[1422351866] transaction","detail":"{read_only:false; response_revision:18964; number_of_response:1; }","duration":"130.40269ms","start":"2026-10-15T14:56:32.812088Z","end":"2026-10-15T14:56:32.942491Z","steps":["trace[1422351866] 'process raft request' (duration: 101.292166ms)","trace[1422351866] 'compare' (duration: 28.723685ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:57:00.806263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"48afe2e91b68f008","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:57:00.811956Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:57:00.812062Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:57:28.375729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17058} {"level":"info","ts":"2026-10-15T14:57:28.486063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17058,"took":"107.112774ms","hash":3639445467,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":33583104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:57:28.486141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3639445467,"revision":17058,"compact-revision":14602} {"level":"info","ts":"2026-10-15T15:01:20.757527Z","caller":"traceutil/trace.go:171","msg":"trace[346311712] transaction","detail":"{read_only:false; response_revision:21229; number_of_response:1; }","duration":"186.510958ms","start":"2026-10-15T15:01:20.570987Z","end":"2026-10-15T15:01:20.757498Z","steps":["trace[346311712] 'process raft request' (duration: 186.296703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:31.239904Z","caller":"traceutil/trace.go:171","msg":"trace[985722401] transaction","detail":"{read_only:false; response_revision:21282; number_of_response:1; }","duration":"136.609008ms","start":"2026-10-15T15:01:31.103268Z","end":"2026-10-15T15:01:31.239877Z","steps":["trace[985722401] 'process raft request' (duration: 136.420404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:36.529003Z","caller":"traceutil/trace.go:171","msg":"trace[8804028] transaction","detail":"{read_only:false; response_revision:21308; number_of_response:1; }","duration":"100.293602ms","start":"2026-10-15T15:01:36.428687Z","end":"2026-10-15T15:01:36.52898Z","steps":["trace[8804028] 'process raft request' (duration: 99.909753ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:36.752866Z","caller":"traceutil/trace.go:171","msg":"trace[220043022] linearizableReadLoop","detail":"{readStateIndex:22031; appliedIndex:22030; }","duration":"180.551186ms","start":"2026-10-15T15:01:36.572279Z","end":"2026-10-15T15:01:36.752831Z","steps":["trace[220043022] 'read index received' (duration: 102.246648ms)","trace[220043022] 'applied index is now lower than readState.Index' (duration: 78.303258ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:01:36.752938Z","caller":"traceutil/trace.go:171","msg":"trace[643988050] transaction","detail":"{read_only:false; response_revision:21310; number_of_response:1; }","duration":"220.590391ms","start":"2026-10-15T15:01:36.532314Z","end":"2026-10-15T15:01:36.752905Z","steps":["trace[643988050] 'process raft request' (duration: 142.261723ms)","trace[643988050] 'compare' (duration: 78.140954ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:01:36.753105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.872424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T15:01:36.753183Z","caller":"traceutil/trace.go:171","msg":"trace[1826705164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21310; }","duration":"180.991207ms","start":"2026-10-15T15:01:36.572172Z","end":"2026-10-15T15:01:36.753163Z","steps":["trace[1826705164] 'agreement among raft nodes before linearized reading' (duration: 180.776402ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:36.898391Z","caller":"traceutil/trace.go:171","msg":"trace[5532452] linearizableReadLoop","detail":"{readStateIndex:22032; appliedIndex:22031; }","duration":"112.050951ms","start":"2026-10-15T15:01:36.786313Z","end":"2026-10-15T15:01:36.898364Z","steps":["trace[5532452] 'read index received' (duration: 66.131307ms)","trace[5532452] 'applied index is now lower than readState.Index' (duration: 45.918224ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:01:36.898478Z","caller":"traceutil/trace.go:171","msg":"trace[105703314] transaction","detail":"{read_only:false; response_revision:21311; number_of_response:1; }","duration":"141.216327ms","start":"2026-10-15T15:01:36.757212Z","end":"2026-10-15T15:01:36.898428Z","steps":["trace[105703314] 'process raft request' (duration: 95.230621ms)","trace[105703314] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 45.755161ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:01:36.89853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.198184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:01:36.89857Z","caller":"traceutil/trace.go:171","msg":"trace[961495113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:21311; }","duration":"112.302087ms","start":"2026-10-15T15:01:36.786257Z","end":"2026-10-15T15:01:36.898559Z","steps":["trace[961495113] 'agreement among raft nodes before linearized reading' (duration: 112.203225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:36.944622Z","caller":"traceutil/trace.go:171","msg":"trace[1218670883] transaction","detail":"{read_only:false; response_revision:21312; number_of_response:1; }","duration":"127.262674ms","start":"2026-10-15T15:01:36.817334Z","end":"2026-10-15T15:01:36.944597Z","steps":["trace[1218670883] 'process raft request' (duration: 127.116571ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:28.382475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19550} {"level":"info","ts":"2026-10-15T15:02:28.512221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19550,"took":"126.262911ms","hash":3919601450,"current-db-size-bytes":55263232,"current-db-size":"55 MB","current-db-size-in-use-bytes":30482432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:02:28.512277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919601450,"revision":19550,"compact-revision":17058} {"level":"info","ts":"2026-10-15T15:03:25.560001Z","caller":"traceutil/trace.go:171","msg":"trace[1333223975] transaction","detail":"{read_only:false; response_revision:22213; number_of_response:1; }","duration":"126.034396ms","start":"2026-10-15T15:03:25.433692Z","end":"2026-10-15T15:03:25.559727Z","steps":["trace[1333223975] 'process raft request' (duration: 61.164289ms)","trace[1333223975] 'compare' (duration: 64.492768ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:28.389017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21845} {"level":"info","ts":"2026-10-15T15:07:28.52005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21845,"took":"127.127143ms","hash":2853450884,"current-db-size-bytes":56950784,"current-db-size":"57 MB","current-db-size-in-use-bytes":44027904,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-15T15:07:28.520118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2853450884,"revision":21845,"compact-revision":19550} {"level":"info","ts":"2026-10-15T15:08:59.940053Z","caller":"traceutil/trace.go:171","msg":"trace[1645906745] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"110.096991ms","start":"2026-10-15T15:08:59.829931Z","end":"2026-10-15T15:08:59.940028Z","steps":["trace[1645906745] 'process raft request' (duration: 109.899076ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:00.291448Z","caller":"traceutil/trace.go:171","msg":"trace[1268720750] linearizableReadLoop","detail":"{readStateIndex:26684; appliedIndex:26683; }","duration":"343.370228ms","start":"2026-10-15T15:08:59.948049Z","end":"2026-10-15T15:09:00.291419Z","steps":["trace[1268720750] 'read index received' (duration: 343.175933ms)","trace[1268720750] 'applied index is now lower than readState.Index' (duration: 193.025µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:09:00.291514Z","caller":"traceutil/trace.go:171","msg":"trace[1102332535] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"366.583272ms","start":"2026-10-15T15:08:59.924895Z","end":"2026-10-15T15:09:00.291478Z","steps":["trace[1102332535] 'process raft request' (duration: 366.376557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:00.291594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.534821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:09:00.291683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:08:59.924871Z","time spent":"366.725846ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:09:00.291831Z","caller":"traceutil/trace.go:171","msg":"trace[2005379879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25838; }","duration":"343.823438ms","start":"2026-10-15T15:08:59.947992Z","end":"2026-10-15T15:09:00.291816Z","steps":["trace[2005379879] 'agreement among raft nodes before linearized reading' (duration: 343.528661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:00.292116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:08:59.947912Z","time spent":"344.187937ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T15:09:00.755542Z","caller":"traceutil/trace.go:171","msg":"trace[11633120] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"143.140961ms","start":"2026-10-15T15:09:00.612369Z","end":"2026-10-15T15:09:00.75551Z","steps":["trace[11633120] 'process raft request' (duration: 142.946156ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:00.829888Z","caller":"traceutil/trace.go:171","msg":"trace[326373283] linearizableReadLoop","detail":"{readStateIndex:26686; appliedIndex:26684; }","duration":"101.532967ms","start":"2026-10-15T15:09:00.728321Z","end":"2026-10-15T15:09:00.829854Z","steps":["trace[326373283] 'read index received' (duration: 26.997575ms)","trace[326373283] 'applied index is now lower than readState.Index' (duration: 74.533642ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:09:00.829939Z","caller":"traceutil/trace.go:171","msg":"trace[1476912454] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"121.580345ms","start":"2026-10-15T15:09:00.708329Z","end":"2026-10-15T15:09:00.829909Z","steps":["trace[1476912454] 'process raft request' (duration: 113.880151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:00.830112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.774443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T15:09:00.83019Z","caller":"traceutil/trace.go:171","msg":"trace[1326947346] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25840; }","duration":"101.894045ms","start":"2026-10-15T15:09:00.728274Z","end":"2026-10-15T15:09:00.830168Z","steps":["trace[1326947346] 'agreement among raft nodes before linearized reading' (duration: 101.702351ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:08.989385Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000687e.wal"} {"level":"info","ts":"2026-10-15T15:10:01.260069Z","caller":"traceutil/trace.go:171","msg":"trace[502107639] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"139.71506ms","start":"2026-10-15T15:10:01.120309Z","end":"2026-10-15T15:10:01.260024Z","steps":["trace[502107639] 'process raft request' (duration: 131.707589ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.26009Z","caller":"traceutil/trace.go:171","msg":"trace[2048340038] linearizableReadLoop","detail":"{readStateIndex:27253; appliedIndex:27252; }","duration":"106.740152ms","start":"2026-10-15T15:10:01.15327Z","end":"2026-10-15T15:10:01.26001Z","steps":["trace[2048340038] 'read index received' (duration: 98.773481ms)","trace[2048340038] 'applied index is now lower than readState.Index' (duration: 7.964161ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:10:01.260465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.187312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T15:10:01.260542Z","caller":"traceutil/trace.go:171","msg":"trace[1701040152] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26394; }","duration":"107.331846ms","start":"2026-10-15T15:10:01.153188Z","end":"2026-10-15T15:10:01.26052Z","steps":["trace[1701040152] 'agreement among raft nodes before linearized reading' (duration: 106.956087ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.464845Z","caller":"traceutil/trace.go:171","msg":"trace[1986347232] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"275.312521ms","start":"2026-10-15T15:10:01.189501Z","end":"2026-10-15T15:10:01.464814Z","steps":["trace[1986347232] 'process raft request' (duration: 275.243139ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.465168Z","caller":"traceutil/trace.go:171","msg":"trace[847631926] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"275.972327ms","start":"2026-10-15T15:10:01.189181Z","end":"2026-10-15T15:10:01.465154Z","steps":["trace[847631926] 'process raft request' (duration: 251.463971ms)","trace[847631926] 'compare' (duration: 23.920012ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:10:01.661474Z","caller":"traceutil/trace.go:171","msg":"trace[456915500] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"405.42562ms","start":"2026-10-15T15:10:01.256007Z","end":"2026-10-15T15:10:01.661432Z","steps":["trace[456915500] 'process raft request' (duration: 404.964729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.661797Z","caller":"traceutil/trace.go:171","msg":"trace[1677705437] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"396.756203ms","start":"2026-10-15T15:10:01.264971Z","end":"2026-10-15T15:10:01.661727Z","steps":["trace[1677705437] 'process raft request' (duration: 396.197929ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.661876Z","caller":"traceutil/trace.go:171","msg":"trace[1755698079] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"350.048076ms","start":"2026-10-15T15:10:01.311791Z","end":"2026-10-15T15:10:01.661839Z","steps":["trace[1755698079] 'process raft request' (duration: 349.753649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:01.661966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:10:01.255982Z","time spent":"405.85775ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T15:10:01.66166Z","caller":"traceutil/trace.go:171","msg":"trace[412396675] linearizableReadLoop","detail":"{readStateIndex:27256; appliedIndex:27253; }","duration":"401.414563ms","start":"2026-10-15T15:10:01.260213Z","end":"2026-10-15T15:10:01.661628Z","steps":["trace[412396675] 'read index received' (duration: 180.674368ms)","trace[412396675] 'applied index is now lower than readState.Index' (duration: 220.738655ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:10:01.662036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:10:01.26496Z","time spent":"396.983548ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:10:01.66208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:10:01.31173Z","time spent":"350.245671ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:10:01.662077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.460949ms","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-15T15:10:01.662151Z","caller":"traceutil/trace.go:171","msg":"trace[1814925077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26399; }","duration":"502.584172ms","start":"2026-10-15T15:10:01.159547Z","end":"2026-10-15T15:10:01.662131Z","steps":["trace[1814925077] 'agreement among raft nodes before linearized reading' (duration: 502.351427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:01.662196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:10:01.159525Z","time spent":"502.661834ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T15:10:01.731092Z","caller":"traceutil/trace.go:171","msg":"trace[1770204845] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"286.910797ms","start":"2026-10-15T15:10:01.44416Z","end":"2026-10-15T15:10:01.731071Z","steps":["trace[1770204845] 'process raft request' (duration: 286.634991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:01.73164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.426182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:10:01.731729Z","caller":"traceutil/trace.go:171","msg":"trace[379807415] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26401; }","duration":"421.576487ms","start":"2026-10-15T15:10:01.31013Z","end":"2026-10-15T15:10:01.731706Z","steps":["trace[379807415] 'agreement among raft nodes before linearized reading' (duration: 421.428643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:01.731726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.198794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-15T15:10:01.73165Z","caller":"traceutil/trace.go:171","msg":"trace[278272220] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"219.405624ms","start":"2026-10-15T15:10:01.51223Z","end":"2026-10-15T15:10:01.731636Z","steps":["trace[278272220] 'process raft request' (duration: 218.724198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:01.731819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:10:01.310106Z","time spent":"421.698519ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-10-15T15:10:01.731853Z","caller":"traceutil/trace.go:171","msg":"trace[1525872552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26401; }","duration":"264.376648ms","start":"2026-10-15T15:10:01.467439Z","end":"2026-10-15T15:10:01.731816Z","steps":["trace[1525872552] 'agreement among raft nodes before linearized reading' (duration: 264.111962ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.918789Z","caller":"traceutil/trace.go:171","msg":"trace[422911192] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"251.43199ms","start":"2026-10-15T15:10:01.667325Z","end":"2026-10-15T15:10:01.918757Z","steps":["trace[422911192] 'process raft request' (duration: 224.067586ms)","trace[422911192] 'compare' (duration: 26.998186ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:10:01.918866Z","caller":"traceutil/trace.go:171","msg":"trace[770969922] transaction","detail":"{read_only:false; response_revision:26403; number_of_response:1; }","duration":"181.277903ms","start":"2026-10-15T15:10:01.73756Z","end":"2026-10-15T15:10:01.918838Z","steps":["trace[770969922] 'process raft request' (duration: 181.179351ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:01.918891Z","caller":"traceutil/trace.go:171","msg":"trace[969241941] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27260; }","duration":"187.370119ms","start":"2026-10-15T15:10:01.731491Z","end":"2026-10-15T15:10:01.918861Z","steps":["trace[969241941] 'read index received' (duration: 159.911492ms)","trace[969241941] 'applied index is now lower than readState.Index' (duration: 27.455277ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:10:01.919124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.022601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T15:10:01.919175Z","caller":"traceutil/trace.go:171","msg":"trace[2008865699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26403; }","duration":"246.119903ms","start":"2026-10-15T15:10:01.673044Z","end":"2026-10-15T15:10:01.919164Z","steps":["trace[2008865699] 'agreement among raft nodes before linearized reading' (duration: 245.924299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:02.173973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.34413ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-15T15:10:02.174288Z","caller":"traceutil/trace.go:171","msg":"trace[291029902] linearizableReadLoop","detail":"{readStateIndex:27264; appliedIndex:27262; }","duration":"226.693209ms","start":"2026-10-15T15:10:01.947566Z","end":"2026-10-15T15:10:02.174259Z","steps":["trace[291029902] 'read index received' (duration: 95.108793ms)","trace[291029902] 'applied index is now lower than readState.Index' (duration: 131.582866ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:10:02.174413Z","caller":"traceutil/trace.go:171","msg":"trace[1295865655] transaction","detail":"{read_only:false; response_revision:26404; number_of_response:1; }","duration":"251.238946ms","start":"2026-10-15T15:10:01.923157Z","end":"2026-10-15T15:10:02.174396Z","steps":["trace[1295865655] 'process raft request' (duration: 250.933349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:02.174737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.16268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:10:02.174803Z","caller":"traceutil/trace.go:171","msg":"trace[1185359818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26404; }","duration":"227.278102ms","start":"2026-10-15T15:10:01.947508Z","end":"2026-10-15T15:10:02.174786Z","steps":["trace[1185359818] 'agreement among raft nodes before linearized reading' (duration: 227.157259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:02.174854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.664443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T15:10:02.174969Z","caller":"traceutil/trace.go:171","msg":"trace[299950112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26404; }","duration":"158.831447ms","start":"2026-10-15T15:10:02.016114Z","end":"2026-10-15T15:10:02.174945Z","steps":["trace[299950112] 'agreement among raft nodes before linearized reading' (duration: 158.566561ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:28.395937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25003} {"level":"info","ts":"2026-10-15T15:12:28.564001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25003,"took":"164.031842ms","hash":2389443012,"current-db-size-bytes":59580416,"current-db-size":"60 MB","current-db-size-in-use-bytes":36745216,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T15:12:28.564072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2389443012,"revision":25003,"compact-revision":21845} {"level":"info","ts":"2026-10-15T15:14:37.393577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"48afe2e91b68f008","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:14:37.399539Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:14:37.399624Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T15:17:28.41211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27798} {"level":"info","ts":"2026-10-15T15:17:28.556742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27798,"took":"138.791038ms","hash":2510774175,"current-db-size-bytes":59580416,"current-db-size":"60 MB","current-db-size-in-use-bytes":33579008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T15:17:28.556819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510774175,"revision":27798,"compact-revision":25003} {"level":"info","ts":"2026-10-15T15:22:28.421891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30367} {"level":"info","ts":"2026-10-15T15:22:28.567229Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30367,"took":"141.387804ms","hash":2499513716,"current-db-size-bytes":59580416,"current-db-size":"60 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:22:28.567299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2499513716,"revision":30367,"compact-revision":27798} {"level":"info","ts":"2026-10-15T15:26:33.467006Z","caller":"traceutil/trace.go:171","msg":"trace[1145693409] transaction","detail":"{read_only:false; response_revision:34446; number_of_response:1; }","duration":"106.450031ms","start":"2026-10-15T15:26:33.360528Z","end":"2026-10-15T15:26:33.466978Z","steps":["trace[1145693409] 'process raft request' (duration: 96.745309ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:33.467258Z","caller":"traceutil/trace.go:171","msg":"trace[1769802318] transaction","detail":"{read_only:false; response_revision:34447; number_of_response:1; }","duration":"106.220305ms","start":"2026-10-15T15:26:33.361012Z","end":"2026-10-15T15:26:33.467232Z","steps":["trace[1769802318] 'process raft request' (duration: 105.923398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:34.023964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.473485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:26:34.024189Z","caller":"traceutil/trace.go:171","msg":"trace[171334749] transaction","detail":"{read_only:false; response_revision:34450; number_of_response:1; }","duration":"298.580185ms","start":"2026-10-15T15:26:33.725591Z","end":"2026-10-15T15:26:34.024171Z","steps":["trace[171334749] 'process raft request' (duration: 298.532794ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:34.024273Z","caller":"traceutil/trace.go:171","msg":"trace[1132842250] transaction","detail":"{read_only:false; response_revision:34448; number_of_response:1; }","duration":"546.657316ms","start":"2026-10-15T15:26:33.477546Z","end":"2026-10-15T15:26:34.024203Z","steps":["trace[1132842250] 'process raft request' (duration: 218.859533ms)","trace[1132842250] 'compare' (duration: 327.351652ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:34.024386Z","caller":"traceutil/trace.go:171","msg":"trace[655939355] transaction","detail":"{read_only:false; response_revision:34449; number_of_response:1; }","duration":"329.209657ms","start":"2026-10-15T15:26:33.695128Z","end":"2026-10-15T15:26:34.024338Z","steps":["trace[655939355] 'process raft request' (duration: 328.939531ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:34.024345Z","caller":"traceutil/trace.go:171","msg":"trace[593241241] linearizableReadLoop","detail":"{readStateIndex:35529; appliedIndex:35528; }","duration":"541.863671ms","start":"2026-10-15T15:26:33.482314Z","end":"2026-10-15T15:26:34.024178Z","steps":["trace[593241241] 'read index received' (duration: 214.056098ms)","trace[593241241] 'applied index is now lower than readState.Index' (duration: 327.803903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:26:34.024487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:26:33.695109Z","time spent":"329.32742ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:26:34.024562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:26:33.477517Z","time spent":"546.912802ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:26:34.024589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.260822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T15:26:34.024646Z","caller":"traceutil/trace.go:171","msg":"trace[1215425206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:34450; }","duration":"542.355323ms","start":"2026-10-15T15:26:33.482277Z","end":"2026-10-15T15:26:34.024632Z","steps":["trace[1215425206] 'agreement among raft nodes before linearized reading' (duration: 542.178409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:34.02467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:26:33.482264Z","time spent":"542.401155ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T15:26:34.327904Z","caller":"traceutil/trace.go:171","msg":"trace[1548594332] transaction","detail":"{read_only:false; response_revision:34451; number_of_response:1; }","duration":"298.395041ms","start":"2026-10-15T15:26:34.029478Z","end":"2026-10-15T15:26:34.327873Z","steps":["trace[1548594332] 'process raft request' (duration: 298.197186ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:00.37126Z","caller":"traceutil/trace.go:171","msg":"trace[1968393959] linearizableReadLoop","detail":"{readStateIndex:35729; appliedIndex:35727; }","duration":"138.2918ms","start":"2026-10-15T15:27:00.232947Z","end":"2026-10-15T15:27:00.371239Z","steps":["trace[1968393959] 'read index received' (duration: 59.429938ms)","trace[1968393959] 'applied index is now lower than readState.Index' (duration: 78.860532ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:00.371328Z","caller":"traceutil/trace.go:171","msg":"trace[1323621043] transaction","detail":"{read_only:false; response_revision:34643; number_of_response:1; }","duration":"153.639827ms","start":"2026-10-15T15:27:00.217656Z","end":"2026-10-15T15:27:00.371295Z","steps":["trace[1323621043] 'process raft request' (duration: 153.460942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:00.371454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.502686ms","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-15T15:27:00.371579Z","caller":"traceutil/trace.go:171","msg":"trace[1559303768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34643; }","duration":"138.585477ms","start":"2026-10-15T15:27:00.232895Z","end":"2026-10-15T15:27:00.371481Z","steps":["trace[1559303768] 'agreement among raft nodes before linearized reading' (duration: 138.437684ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:28.430069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32620} {"level":"info","ts":"2026-10-15T15:27:28.59593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32620,"took":"161.523945ms","hash":3658139210,"current-db-size-bytes":60620800,"current-db-size":"61 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:27:28.59599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3658139210,"revision":32620,"compact-revision":30367} {"level":"info","ts":"2026-10-15T15:32:28.437276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34844} {"level":"info","ts":"2026-10-15T15:32:28.564106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34844,"took":"122.879071ms","hash":2247227078,"current-db-size-bytes":60620800,"current-db-size":"61 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T15:32:28.564194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247227078,"revision":34844,"compact-revision":32620} {"level":"info","ts":"2026-10-15T15:36:04.262669Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"48afe2e91b68f008","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:36:04.270077Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:36:04.270191Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:37:28.444233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37141} {"level":"info","ts":"2026-10-15T15:37:28.571454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37141,"took":"122.932001ms","hash":356900352,"current-db-size-bytes":61046784,"current-db-size":"61 MB","current-db-size-in-use-bytes":32526336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:37:28.571591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":356900352,"revision":37141,"compact-revision":34844} {"level":"info","ts":"2026-10-15T15:42:28.453207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39417} {"level":"info","ts":"2026-10-15T15:42:28.586942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39417,"took":"130.311429ms","hash":1403245392,"current-db-size-bytes":61898752,"current-db-size":"62 MB","current-db-size-in-use-bytes":31531008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:42:28.586997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1403245392,"revision":39417,"compact-revision":37141}