{"level":"warn","ts":"2026-09-18T02:22:12.608241Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T02:22:12.608504Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.161: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.161:2380","--initial-cluster=instance=https://162.253.55.161:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.161:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.161: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-09-18T02:22:12.608776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T02:22:12.608803Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-09-18T02:22:12.60894Z","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-09-18T02:22:12.610039Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"]} {"level":"info","ts":"2026-09-18T02:22:12.610205Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.161: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-09-18T02:22:12.614731Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.131969ms"} {"level":"info","ts":"2026-09-18T02:22:12.623411Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"383aa7db69884d67","cluster-id":"2ae15ce520a2ef1e"} {"level":"info","ts":"2026-09-18T02:22:12.623691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T02:22:12.623728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 0"} {"level":"info","ts":"2026-09-18T02:22:12.62374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 383aa7db69884d67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T02:22:12.623776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 1"} {"level":"info","ts":"2026-09-18T02:22:12.623862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"warn","ts":"2026-09-18T02:22:12.631723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T02:22:12.633182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T02:22:12.634749Z","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-09-18T02:22:12.636938Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"383aa7db69884d67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T02:22:12.637045Z","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-09-18T02:22:12.63712Z","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-09-18T02:22:12.637146Z","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-09-18T02:22:12.637395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"383aa7db69884d67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T02:22:12.639944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"info","ts":"2026-09-18T02:22:12.640103Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","added-peer-id":"383aa7db69884d67","added-peer-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-09-18T02:22:12.640937Z","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-09-18T02:22:12.64111Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"383aa7db69884d67","initial-advertise-peer-urls":["https://162.253.55.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T02:22:12.641135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T02:22:12.641218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-09-18T02:22:12.641228Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-09-18T02:22:13.524553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T02:22:13.524609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T02:22:13.524665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgPreVoteResp from 383aa7db69884d67 at term 1"} {"level":"info","ts":"2026-09-18T02:22:13.524686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became candidate at term 2"} {"level":"info","ts":"2026-09-18T02:22:13.524696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgVoteResp from 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-09-18T02:22:13.524714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became leader at term 2"} {"level":"info","ts":"2026-09-18T02:22:13.524727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 383aa7db69884d67 elected leader 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-09-18T02:22:13.527472Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"383aa7db69884d67","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.161:2379]}","request-path":"/0/members/383aa7db69884d67/attributes","cluster-id":"2ae15ce520a2ef1e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T02:22:13.527669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:22:13.527809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:22:13.528138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:22:13.529027Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:22:13.529202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:22:13.529361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:22:13.530664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T02:22:13.530887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.161:2379"} {"level":"info","ts":"2026-09-18T02:22:13.533889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T02:22:13.53391Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T02:22:18.352679Z","caller":"traceutil/trace.go:171","msg":"trace[628901876] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"356.2472ms","start":"2026-09-18T02:22:17.996396Z","end":"2026-09-18T02:22:18.352643Z","steps":["trace[628901876] 'process raft request' (duration: 355.986799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:18.35359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:17.996363Z","time spent":"356.501682ms","remote":"127.0.0.1:58966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:18.685162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.38241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:18.685392Z","caller":"traceutil/trace.go:171","msg":"trace[1071705379] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"311.707597ms","start":"2026-09-18T02:22:18.373599Z","end":"2026-09-18T02:22:18.685307Z","steps":["trace[1071705379] 'process raft request' (duration: 79.865577ms)","trace[1071705379] 'compare' (duration: 231.144738ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:18.685511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:18.373562Z","time spent":"311.919076ms","remote":"127.0.0.1:58914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:23:00.591359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.310722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-09-18T02:23:00.591478Z","caller":"traceutil/trace.go:171","msg":"trace[946226597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:616; }","duration":"177.488476ms","start":"2026-09-18T02:23:00.41396Z","end":"2026-09-18T02:23:00.591448Z","steps":["trace[946226597] 'range keys from in-memory index tree' (duration: 177.127736ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:06.070937Z","caller":"traceutil/trace.go:171","msg":"trace[1395847224] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"116.213926ms","start":"2026-09-18T02:23:05.954685Z","end":"2026-09-18T02:23:06.070899Z","steps":["trace[1395847224] 'process raft request' (duration: 16.420801ms)","trace[1395847224] 'compare' (duration: 99.473207ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:06.310338Z","caller":"traceutil/trace.go:171","msg":"trace[1799526136] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"119.371678ms","start":"2026-09-18T02:23:06.190928Z","end":"2026-09-18T02:23:06.310299Z","steps":["trace[1799526136] 'process raft request' (duration: 118.631388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:06.881147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.016067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:23:06.881313Z","caller":"traceutil/trace.go:171","msg":"trace[1998782634] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"493.74311ms","start":"2026-09-18T02:23:06.387546Z","end":"2026-09-18T02:23:06.881289Z","steps":["trace[1998782634] 'process raft request' (duration: 366.474996ms)","trace[1998782634] 'compare' (duration: 126.813582ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:06.881386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:06.387521Z","time spent":"493.840083ms","remote":"127.0.0.1:58984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:23:06.943551Z","caller":"traceutil/trace.go:171","msg":"trace[1255974894] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"225.877409ms","start":"2026-09-18T02:23:06.717603Z","end":"2026-09-18T02:23:06.94348Z","steps":["trace[1255974894] 'process raft request' (duration: 225.691683ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:58.366613Z","caller":"traceutil/trace.go:171","msg":"trace[1266433013] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"307.383737ms","start":"2026-09-18T02:23:58.059198Z","end":"2026-09-18T02:23:58.366582Z","steps":["trace[1266433013] 'process raft request' (duration: 307.0298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:58.36678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:58.059164Z","time spent":"307.532979ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:24:38.693672Z","caller":"traceutil/trace.go:171","msg":"trace[2017072280] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"106.242409ms","start":"2026-09-18T02:24:38.587377Z","end":"2026-09-18T02:24:38.693619Z","steps":["trace[2017072280] 'process raft request' (duration: 105.966055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:39.281018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.100292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/pxc-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:24:39.281082Z","caller":"traceutil/trace.go:171","msg":"trace[375990275] range","detail":"{range_begin:/registry/serviceaccounts/openstack/pxc-operator; range_end:; response_count:0; response_revision:1763; }","duration":"137.221553ms","start":"2026-09-18T02:24:39.143846Z","end":"2026-09-18T02:24:39.281068Z","steps":["trace[375990275] 'range keys from in-memory index tree' (duration: 137.00765ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:39.28117Z","caller":"traceutil/trace.go:171","msg":"trace[1756281386] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"133.830402ms","start":"2026-09-18T02:24:39.147294Z","end":"2026-09-18T02:24:39.281125Z","steps":["trace[1756281386] 'process raft request' (duration: 133.537318ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:39.635013Z","caller":"traceutil/trace.go:171","msg":"trace[1759016112] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"109.803547ms","start":"2026-09-18T02:24:39.525181Z","end":"2026-09-18T02:24:39.634985Z","steps":["trace[1759016112] 'process raft request' (duration: 101.142066ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:39.944792Z","caller":"traceutil/trace.go:171","msg":"trace[1760508357] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"101.283137ms","start":"2026-09-18T02:24:39.843481Z","end":"2026-09-18T02:24:39.944764Z","steps":["trace[1760508357] 'process raft request' (duration: 24.923178ms)","trace[1760508357] 'compare' (duration: 74.994329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:53.299323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.361541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:24:53.299441Z","caller":"traceutil/trace.go:171","msg":"trace[268645317] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"300.509218ms","start":"2026-09-18T02:24:52.998914Z","end":"2026-09-18T02:24:53.299423Z","steps":["trace[268645317] 'read index received' (duration: 56.850963ms)","trace[268645317] 'applied index is now lower than readState.Index' (duration: 243.656735ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:53.299607Z","caller":"traceutil/trace.go:171","msg":"trace[394497159] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"389.738194ms","start":"2026-09-18T02:24:52.909856Z","end":"2026-09-18T02:24:53.299594Z","steps":["trace[394497159] 'process raft request' (duration: 145.971867ms)","trace[394497159] 'compare' (duration: 243.085727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:53.299667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:52.909796Z","time spent":"389.833315ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:24:53.299953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.046215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d6491cf57ff8d9\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-18T02:24:53.29999Z","caller":"traceutil/trace.go:171","msg":"trace[1863204775] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d6491cf57ff8d9; range_end:; response_count:1; response_revision:1940; }","duration":"301.107286ms","start":"2026-09-18T02:24:52.99887Z","end":"2026-09-18T02:24:53.299977Z","steps":["trace[1863204775] 'agreement among raft nodes before linearized reading' (duration: 300.970444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:53.300016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:52.998854Z","time spent":"301.156176ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d6491cf57ff8d9\" "} {"level":"warn","ts":"2026-09-18T02:24:53.377538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.216237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-18T02:24:53.377598Z","caller":"traceutil/trace.go:171","msg":"trace[1084066399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1941; }","duration":"209.315498ms","start":"2026-09-18T02:24:53.168271Z","end":"2026-09-18T02:24:53.377587Z","steps":["trace[1084066399] 'agreement among raft nodes before linearized reading' (duration: 209.139635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:53.377637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.734395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T02:24:53.37772Z","caller":"traceutil/trace.go:171","msg":"trace[1171233936] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1941; }","duration":"321.845487ms","start":"2026-09-18T02:24:53.055853Z","end":"2026-09-18T02:24:53.377698Z","steps":["trace[1171233936] 'agreement among raft nodes before linearized reading' (duration: 321.521952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:53.377865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:53.055838Z","time spent":"322.009769ms","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-18T02:24:53.377796Z","caller":"traceutil/trace.go:171","msg":"trace[447496824] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"285.48212ms","start":"2026-09-18T02:24:53.092287Z","end":"2026-09-18T02:24:53.377769Z","steps":["trace[447496824] 'process raft request' (duration: 284.915272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:53.378045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.891845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T02:24:53.378119Z","caller":"traceutil/trace.go:171","msg":"trace[397992908] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1941; }","duration":"308.000397ms","start":"2026-09-18T02:24:53.070101Z","end":"2026-09-18T02:24:53.378101Z","steps":["trace[397992908] 'agreement among raft nodes before linearized reading' (duration: 307.759073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:53.378151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:53.070089Z","time spent":"308.053607ms","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-18T02:24:53.475716Z","caller":"traceutil/trace.go:171","msg":"trace[2002675142] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"102.792088ms","start":"2026-09-18T02:24:53.372894Z","end":"2026-09-18T02:24:53.475686Z","steps":["trace[2002675142] 'process raft request' (duration: 102.701396ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:53.4758Z","caller":"traceutil/trace.go:171","msg":"trace[1315427762] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"171.701984ms","start":"2026-09-18T02:24:53.304066Z","end":"2026-09-18T02:24:53.475768Z","steps":["trace[1315427762] 'process raft request' (duration: 168.155702ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:53.485626Z","caller":"traceutil/trace.go:171","msg":"trace[361920489] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"102.32824ms","start":"2026-09-18T02:24:53.383276Z","end":"2026-09-18T02:24:53.485604Z","steps":["trace[361920489] 'process raft request' (duration: 101.624569ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:53.767923Z","caller":"traceutil/trace.go:171","msg":"trace[2118601090] linearizableReadLoop","detail":"{readStateIndex:2019; appliedIndex:2018; }","duration":"126.272928ms","start":"2026-09-18T02:24:53.641621Z","end":"2026-09-18T02:24:53.767894Z","steps":["trace[2118601090] 'read index received' (duration: 48.403111ms)","trace[2118601090] 'applied index is now lower than readState.Index' (duration: 77.868517ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:53.767976Z","caller":"traceutil/trace.go:171","msg":"trace[620805110] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"272.067697ms","start":"2026-09-18T02:24:53.495852Z","end":"2026-09-18T02:24:53.767919Z","steps":["trace[620805110] 'process raft request' (duration: 194.25375ms)","trace[620805110] 'compare' (duration: 77.640843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:53.768124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.475321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:24:53.768327Z","caller":"traceutil/trace.go:171","msg":"trace[1276408786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"126.741944ms","start":"2026-09-18T02:24:53.641569Z","end":"2026-09-18T02:24:53.76831Z","steps":["trace[1276408786] 'agreement among raft nodes before linearized reading' (duration: 126.47446ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:01.107734Z","caller":"traceutil/trace.go:171","msg":"trace[451716764] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"111.290513ms","start":"2026-09-18T02:25:00.99642Z","end":"2026-09-18T02:25:01.10771Z","steps":["trace[451716764] 'process raft request' (duration: 107.625071ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:01.688559Z","caller":"traceutil/trace.go:171","msg":"trace[92924386] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"104.149632ms","start":"2026-09-18T02:25:01.584383Z","end":"2026-09-18T02:25:01.688532Z","steps":["trace[92924386] 'process raft request' (duration: 103.858847ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:09.951698Z","caller":"traceutil/trace.go:171","msg":"trace[1797120440] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"151.079788ms","start":"2026-09-18T02:25:09.800566Z","end":"2026-09-18T02:25:09.951645Z","steps":["trace[1797120440] 'process raft request' (duration: 56.063145ms)","trace[1797120440] 'compare' (duration: 94.113111ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:10.305042Z","caller":"traceutil/trace.go:171","msg":"trace[1682500573] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"154.193305ms","start":"2026-09-18T02:25:10.150817Z","end":"2026-09-18T02:25:10.30501Z","steps":["trace[1682500573] 'process raft request' (duration: 153.984493ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:14.13205Z","caller":"traceutil/trace.go:171","msg":"trace[1168386490] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"160.605645ms","start":"2026-09-18T02:25:13.971408Z","end":"2026-09-18T02:25:14.132014Z","steps":["trace[1168386490] 'process raft request' (duration: 160.243939ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:36.475076Z","caller":"traceutil/trace.go:171","msg":"trace[684767297] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2302; }","duration":"207.02771ms","start":"2026-09-18T02:25:36.268009Z","end":"2026-09-18T02:25:36.475037Z","steps":["trace[684767297] 'read index received' (duration: 206.841097ms)","trace[684767297] 'applied index is now lower than readState.Index' (duration: 185.273µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:36.475251Z","caller":"traceutil/trace.go:171","msg":"trace[12032470] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"207.581776ms","start":"2026-09-18T02:25:36.267649Z","end":"2026-09-18T02:25:36.475231Z","steps":["trace[12032470] 'process raft request' (duration: 207.145281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:36.475582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.532717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T02:25:36.475645Z","caller":"traceutil/trace.go:171","msg":"trace[810697208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2220; }","duration":"207.646448ms","start":"2026-09-18T02:25:36.267983Z","end":"2026-09-18T02:25:36.47563Z","steps":["trace[810697208] 'agreement among raft nodes before linearized reading' (duration: 207.431445ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:36.586233Z","caller":"traceutil/trace.go:171","msg":"trace[754866634] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"110.999557ms","start":"2026-09-18T02:25:36.475193Z","end":"2026-09-18T02:25:36.586193Z","steps":["trace[754866634] 'read index received' (duration: 97.468701ms)","trace[754866634] 'applied index is now lower than readState.Index' (duration: 13.529366ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:36.586414Z","caller":"traceutil/trace.go:171","msg":"trace[837277937] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"191.584688ms","start":"2026-09-18T02:25:36.394811Z","end":"2026-09-18T02:25:36.586396Z","steps":["trace[837277937] 'process raft request' (duration: 177.662778ms)","trace[837277937] 'compare' (duration: 13.402194ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:36.586561Z","caller":"traceutil/trace.go:171","msg":"trace[1742266675] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"103.438809ms","start":"2026-09-18T02:25:36.483054Z","end":"2026-09-18T02:25:36.586493Z","steps":["trace[1742266675] 'process raft request' (duration: 103.066683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:36.587144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.985864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-09-18T02:25:36.587255Z","caller":"traceutil/trace.go:171","msg":"trace[835180625] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2222; }","duration":"238.128725ms","start":"2026-09-18T02:25:36.349066Z","end":"2026-09-18T02:25:36.587195Z","steps":["trace[835180625] 'agreement among raft nodes before linearized reading' (duration: 237.292264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:36.883174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.570123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T02:25:36.883296Z","caller":"traceutil/trace.go:171","msg":"trace[1782506480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2222; }","duration":"127.762845ms","start":"2026-09-18T02:25:36.755502Z","end":"2026-09-18T02:25:36.883265Z","steps":["trace[1782506480] 'range keys from in-memory index tree' (duration: 127.255879ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:54.092251Z","caller":"traceutil/trace.go:171","msg":"trace[1447581413] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"110.797766ms","start":"2026-09-18T02:25:53.98142Z","end":"2026-09-18T02:25:54.092218Z","steps":["trace[1447581413] 'process raft request' (duration: 108.01537ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:56.071805Z","caller":"traceutil/trace.go:171","msg":"trace[777348223] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"102.71127ms","start":"2026-09-18T02:25:55.96907Z","end":"2026-09-18T02:25:56.071781Z","steps":["trace[777348223] 'process raft request' (duration: 86.360624ms)","trace[777348223] 'compare' (duration: 16.051392ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:58.06728Z","caller":"traceutil/trace.go:171","msg":"trace[1706579468] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"118.023789ms","start":"2026-09-18T02:25:57.949228Z","end":"2026-09-18T02:25:58.067252Z","steps":["trace[1706579468] 'process raft request' (duration: 117.875896ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:06.774264Z","caller":"traceutil/trace.go:171","msg":"trace[91970067] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"316.195641ms","start":"2026-09-18T02:27:06.458037Z","end":"2026-09-18T02:27:06.774232Z","steps":["trace[91970067] 'process raft request' (duration: 291.845655ms)","trace[91970067] 'compare' (duration: 24.253953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:27:06.774419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:27:06.458013Z","time spent":"316.323205ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:27:40.243332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.3411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T02:27:40.243412Z","caller":"traceutil/trace.go:171","msg":"trace[2027050632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2987; }","duration":"167.469854ms","start":"2026-09-18T02:27:40.075926Z","end":"2026-09-18T02:27:40.243395Z","steps":["trace[2027050632] 'range keys from in-memory index tree' (duration: 167.163915ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:40.702486Z","caller":"traceutil/trace.go:171","msg":"trace[538355315] transaction","detail":"{read_only:false; response_revision:2989; number_of_response:1; }","duration":"110.876316ms","start":"2026-09-18T02:27:40.591572Z","end":"2026-09-18T02:27:40.702448Z","steps":["trace[538355315] 'process raft request' (duration: 110.65736ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:13.559487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2846} {"level":"info","ts":"2026-09-18T02:32:13.633685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2846,"took":"73.200846ms","hash":942554195,"current-db-size-bytes":41127936,"current-db-size":"41 MB","current-db-size-in-use-bytes":16642048,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-09-18T02:32:13.633752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":942554195,"revision":2846,"compact-revision":-1} {"level":"info","ts":"2026-09-18T02:32:32.24427Z","caller":"traceutil/trace.go:171","msg":"trace[1819862386] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5282; }","duration":"123.095042ms","start":"2026-09-18T02:32:32.121142Z","end":"2026-09-18T02:32:32.244237Z","steps":["trace[1819862386] 'read index received' (duration: 122.814466ms)","trace[1819862386] 'applied index is now lower than readState.Index' (duration: 279.526µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:32.244483Z","caller":"traceutil/trace.go:171","msg":"trace[597295796] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"126.880534ms","start":"2026-09-18T02:32:32.117584Z","end":"2026-09-18T02:32:32.244465Z","steps":["trace[597295796] 'process raft request' (duration: 126.457077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:32.244853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.680831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-18T02:32:32.244968Z","caller":"traceutil/trace.go:171","msg":"trace[1151463510] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5085; }","duration":"123.816184ms","start":"2026-09-18T02:32:32.121123Z","end":"2026-09-18T02:32:32.244939Z","steps":["trace[1151463510] 'agreement among raft nodes before linearized reading' (duration: 123.435328ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:33.00755Z","caller":"traceutil/trace.go:171","msg":"trace[379722448] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"140.980067ms","start":"2026-09-18T02:32:32.866547Z","end":"2026-09-18T02:32:33.007527Z","steps":["trace[379722448] 'process raft request' (duration: 140.874435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:33.040413Z","caller":"traceutil/trace.go:171","msg":"trace[1061421533] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"141.021287ms","start":"2026-09-18T02:32:32.899373Z","end":"2026-09-18T02:32:33.040394Z","steps":["trace[1061421533] 'process raft request' (duration: 140.491719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:35.040993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.815252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:32:35.041117Z","caller":"traceutil/trace.go:171","msg":"trace[1952851706] linearizableReadLoop","detail":"{readStateIndex:5377; appliedIndex:5376; }","duration":"134.493147ms","start":"2026-09-18T02:32:34.906604Z","end":"2026-09-18T02:32:35.041097Z","steps":["trace[1952851706] 'read index received' (duration: 16.441121ms)","trace[1952851706] 'applied index is now lower than readState.Index' (duration: 118.050426ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:35.041127Z","caller":"traceutil/trace.go:171","msg":"trace[1299558960] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"136.419018ms","start":"2026-09-18T02:32:34.904686Z","end":"2026-09-18T02:32:35.041105Z","steps":["trace[1299558960] 'process raft request' (duration: 18.405743ms)","trace[1299558960] 'compare' (duration: 117.71217ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:35.041315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.705701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T02:32:35.041347Z","caller":"traceutil/trace.go:171","msg":"trace[1823324169] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5175; }","duration":"134.761091ms","start":"2026-09-18T02:32:34.906577Z","end":"2026-09-18T02:32:35.041338Z","steps":["trace[1823324169] 'agreement among raft nodes before linearized reading' (duration: 134.577388ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.29238Z","caller":"traceutil/trace.go:171","msg":"trace[1088084240] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"188.813288ms","start":"2026-09-18T02:32:45.103533Z","end":"2026-09-18T02:32:45.292346Z","steps":["trace[1088084240] 'process raft request' (duration: 178.961407ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.292745Z","caller":"traceutil/trace.go:171","msg":"trace[1663005506] linearizableReadLoop","detail":"{readStateIndex:5504; appliedIndex:5502; }","duration":"177.662246ms","start":"2026-09-18T02:32:45.115043Z","end":"2026-09-18T02:32:45.292706Z","steps":["trace[1663005506] 'read index received' (duration: 167.685513ms)","trace[1663005506] 'applied index is now lower than readState.Index' (duration: 9.974973ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:45.293096Z","caller":"traceutil/trace.go:171","msg":"trace[1108197262] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"183.205638ms","start":"2026-09-18T02:32:45.109874Z","end":"2026-09-18T02:32:45.293079Z","steps":["trace[1108197262] 'process raft request' (duration: 182.357884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:45.293417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.331527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-066fab64-8ca5-4a38-ac85-6fdc9f443488\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-18T02:32:45.293494Z","caller":"traceutil/trace.go:171","msg":"trace[466780491] range","detail":"{range_begin:/registry/persistentvolumes/pvc-066fab64-8ca5-4a38-ac85-6fdc9f443488; range_end:; response_count:1; response_revision:5298; }","duration":"178.44986ms","start":"2026-09-18T02:32:45.115021Z","end":"2026-09-18T02:32:45.293471Z","steps":["trace[466780491] 'agreement among raft nodes before linearized reading' (duration: 178.202436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:45.347959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.547069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T02:32:45.348035Z","caller":"traceutil/trace.go:171","msg":"trace[1234680704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5300; }","duration":"154.682141ms","start":"2026-09-18T02:32:45.193341Z","end":"2026-09-18T02:32:45.348023Z","steps":["trace[1234680704] 'agreement among raft nodes before linearized reading' (duration: 154.480097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:45.348065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.937104ms","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-09-18T02:32:45.348254Z","caller":"traceutil/trace.go:171","msg":"trace[725777826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5300; }","duration":"224.178928ms","start":"2026-09-18T02:32:45.124039Z","end":"2026-09-18T02:32:45.348218Z","steps":["trace[725777826] 'agreement among raft nodes before linearized reading' (duration: 223.667379ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.348802Z","caller":"traceutil/trace.go:171","msg":"trace[331673410] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"223.661999ms","start":"2026-09-18T02:32:45.125113Z","end":"2026-09-18T02:32:45.348775Z","steps":["trace[331673410] 'process raft request' (duration: 222.244116ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.347554Z","caller":"traceutil/trace.go:171","msg":"trace[309758903] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"164.727584ms","start":"2026-09-18T02:32:45.182803Z","end":"2026-09-18T02:32:45.347531Z","steps":["trace[309758903] 'process raft request' (duration: 164.667753ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.527869Z","caller":"traceutil/trace.go:171","msg":"trace[485656926] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"173.753563ms","start":"2026-09-18T02:32:45.354067Z","end":"2026-09-18T02:32:45.52782Z","steps":["trace[485656926] 'process raft request' (duration: 173.521099ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:45.528008Z","caller":"traceutil/trace.go:171","msg":"trace[1760724476] linearizableReadLoop","detail":"{readStateIndex:5512; appliedIndex:5506; }","duration":"172.598843ms","start":"2026-09-18T02:32:45.355395Z","end":"2026-09-18T02:32:45.527993Z","steps":["trace[1760724476] 'read index received' (duration: 35.676984ms)","trace[1760724476] 'applied index is now lower than readState.Index' (duration: 136.920159ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:45.528033Z","caller":"traceutil/trace.go:171","msg":"trace[232869791] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"173.251463ms","start":"2026-09-18T02:32:45.354759Z","end":"2026-09-18T02:32:45.528011Z","steps":["trace[232869791] 'process raft request' (duration: 172.986559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:45.528124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.715606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:32:45.528425Z","caller":"traceutil/trace.go:171","msg":"trace[1874854215] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:5306; }","duration":"173.066292ms","start":"2026-09-18T02:32:45.355312Z","end":"2026-09-18T02:32:45.528379Z","steps":["trace[1874854215] 'agreement among raft nodes before linearized reading' (duration: 172.720086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:53.979475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.615667ms","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-09-18T02:32:53.979711Z","caller":"traceutil/trace.go:171","msg":"trace[412034861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5487; }","duration":"103.81798ms","start":"2026-09-18T02:32:53.875743Z","end":"2026-09-18T02:32:53.979561Z","steps":["trace[412034861] 'range keys from in-memory index tree' (duration: 103.396313ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:58.264688Z","caller":"traceutil/trace.go:171","msg":"trace[980131270] linearizableReadLoop","detail":"{readStateIndex:5731; appliedIndex:5730; }","duration":"142.552393ms","start":"2026-09-18T02:32:58.122115Z","end":"2026-09-18T02:32:58.264667Z","steps":["trace[980131270] 'read index received' (duration: 142.388211ms)","trace[980131270] 'applied index is now lower than readState.Index' (duration: 163.222µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:58.264709Z","caller":"traceutil/trace.go:171","msg":"trace[612442762] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"178.129431ms","start":"2026-09-18T02:32:58.086559Z","end":"2026-09-18T02:32:58.264688Z","steps":["trace[612442762] 'process raft request' (duration: 177.944889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:58.264991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.851468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-18T02:32:58.265081Z","caller":"traceutil/trace.go:171","msg":"trace[2074583177] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5519; }","duration":"142.993092ms","start":"2026-09-18T02:32:58.122067Z","end":"2026-09-18T02:32:58.26506Z","steps":["trace[2074583177] 'agreement among raft nodes before linearized reading' (duration: 142.694316ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:58.508594Z","caller":"traceutil/trace.go:171","msg":"trace[2108243776] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5731; }","duration":"243.819987ms","start":"2026-09-18T02:32:58.264747Z","end":"2026-09-18T02:32:58.508567Z","steps":["trace[2108243776] 'read index received' (duration: 160.75374ms)","trace[2108243776] 'applied index is now lower than readState.Index' (duration: 83.065097ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:58.508811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.140964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T02:32:58.508862Z","caller":"traceutil/trace.go:171","msg":"trace[1953657883] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5520; }","duration":"307.249796ms","start":"2026-09-18T02:32:58.201602Z","end":"2026-09-18T02:32:58.508852Z","steps":["trace[1953657883] 'agreement among raft nodes before linearized reading' (duration: 307.057623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:58.508891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:58.201583Z","time spent":"307.301417ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-18T02:32:58.509073Z","caller":"traceutil/trace.go:171","msg":"trace[1221487709] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"366.725422ms","start":"2026-09-18T02:32:58.142165Z","end":"2026-09-18T02:32:58.50889Z","steps":["trace[1221487709] 'process raft request' (duration: 283.342339ms)","trace[1221487709] 'compare' (duration: 82.909945ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:58.509256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:58.142149Z","time spent":"367.011706ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:32:58.509349Z","caller":"traceutil/trace.go:171","msg":"trace[326728157] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"231.241163ms","start":"2026-09-18T02:32:58.278095Z","end":"2026-09-18T02:32:58.509336Z","steps":["trace[326728157] 'process raft request' (duration: 231.160431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:19.435345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.279382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:36:19.43584Z","caller":"traceutil/trace.go:171","msg":"trace[1308528559] transaction","detail":"{read_only:false; response_revision:6897; number_of_response:1; }","duration":"107.229621ms","start":"2026-09-18T02:36:19.328571Z","end":"2026-09-18T02:36:19.4358Z","steps":["trace[1308528559] 'process raft request' (duration: 106.958582ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:19.435949Z","caller":"traceutil/trace.go:171","msg":"trace[427232967] transaction","detail":"{read_only:false; response_revision:6896; number_of_response:1; }","duration":"129.166546ms","start":"2026-09-18T02:36:19.306742Z","end":"2026-09-18T02:36:19.435908Z","steps":["trace[427232967] 'process raft request' (duration: 27.145867ms)","trace[427232967] 'compare' (duration: 101.017332ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:19.664758Z","caller":"traceutil/trace.go:171","msg":"trace[1651694602] transaction","detail":"{read_only:false; response_revision:6899; number_of_response:1; }","duration":"119.912946ms","start":"2026-09-18T02:36:19.544821Z","end":"2026-09-18T02:36:19.664734Z","steps":["trace[1651694602] 'process raft request' (duration: 22.444465ms)","trace[1651694602] 'compare' (duration: 97.064547ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:20.464965Z","caller":"traceutil/trace.go:171","msg":"trace[753371248] transaction","detail":"{read_only:false; response_revision:6901; number_of_response:1; }","duration":"157.252387ms","start":"2026-09-18T02:36:20.307685Z","end":"2026-09-18T02:36:20.464937Z","steps":["trace[753371248] 'process raft request' (duration: 157.07329ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.804746Z","caller":"traceutil/trace.go:171","msg":"trace[1922401196] transaction","detail":"{read_only:false; response_revision:6958; number_of_response:1; }","duration":"128.071859ms","start":"2026-09-18T02:36:22.676649Z","end":"2026-09-18T02:36:22.80472Z","steps":["trace[1922401196] 'process raft request' (duration: 127.989646ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.805042Z","caller":"traceutil/trace.go:171","msg":"trace[509325030] transaction","detail":"{read_only:false; response_revision:6957; number_of_response:1; }","duration":"130.107703ms","start":"2026-09-18T02:36:22.674923Z","end":"2026-09-18T02:36:22.805031Z","steps":["trace[509325030] 'process raft request' (duration: 129.634866ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.805402Z","caller":"traceutil/trace.go:171","msg":"trace[1539898301] transaction","detail":"{read_only:false; response_revision:6956; number_of_response:1; }","duration":"130.687235ms","start":"2026-09-18T02:36:22.674702Z","end":"2026-09-18T02:36:22.80539Z","steps":["trace[1539898301] 'process raft request' (duration: 91.205343ms)","trace[1539898301] 'compare' (duration: 38.3388ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:22.805665Z","caller":"traceutil/trace.go:171","msg":"trace[801588125] transaction","detail":"{read_only:false; response_revision:6960; number_of_response:1; }","duration":"127.686125ms","start":"2026-09-18T02:36:22.677963Z","end":"2026-09-18T02:36:22.805649Z","steps":["trace[801588125] 'process raft request' (duration: 127.516238ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.805745Z","caller":"traceutil/trace.go:171","msg":"trace[2138367532] transaction","detail":"{read_only:false; response_revision:6959; number_of_response:1; }","duration":"129.026984ms","start":"2026-09-18T02:36:22.676669Z","end":"2026-09-18T02:36:22.805696Z","steps":["trace[2138367532] 'process raft request' (duration: 128.010837ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.805903Z","caller":"traceutil/trace.go:171","msg":"trace[1640174126] transaction","detail":"{read_only:false; response_revision:6961; number_of_response:1; }","duration":"127.834961ms","start":"2026-09-18T02:36:22.678057Z","end":"2026-09-18T02:36:22.805892Z","steps":["trace[1640174126] 'process raft request' (duration: 127.488318ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.806161Z","caller":"traceutil/trace.go:171","msg":"trace[911670813] transaction","detail":"{read_only:false; response_revision:6964; number_of_response:1; }","duration":"111.93657ms","start":"2026-09-18T02:36:22.694215Z","end":"2026-09-18T02:36:22.806152Z","steps":["trace[911670813] 'process raft request' (duration: 111.891228ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.806286Z","caller":"traceutil/trace.go:171","msg":"trace[1968717092] linearizableReadLoop","detail":"{readStateIndex:7223; appliedIndex:7216; }","duration":"126.859695ms","start":"2026-09-18T02:36:22.679415Z","end":"2026-09-18T02:36:22.806274Z","steps":["trace[1968717092] 'read index received' (duration: 9.658474ms)","trace[1968717092] 'applied index is now lower than readState.Index' (duration: 117.199871ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:22.806368Z","caller":"traceutil/trace.go:171","msg":"trace[63156908] transaction","detail":"{read_only:false; response_revision:6963; number_of_response:1; }","duration":"124.792688ms","start":"2026-09-18T02:36:22.681564Z","end":"2026-09-18T02:36:22.806356Z","steps":["trace[63156908] 'process raft request' (duration: 124.181746ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.805864Z","caller":"traceutil/trace.go:171","msg":"trace[378587124] transaction","detail":"{read_only:false; response_revision:6962; number_of_response:1; }","duration":"127.191317ms","start":"2026-09-18T02:36:22.678602Z","end":"2026-09-18T02:36:22.805793Z","steps":["trace[378587124] 'process raft request' (duration: 127.00032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:22.806543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.110435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-18T02:36:22.806583Z","caller":"traceutil/trace.go:171","msg":"trace[2025134654] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:6964; }","duration":"127.180568ms","start":"2026-09-18T02:36:22.67939Z","end":"2026-09-18T02:36:22.806571Z","steps":["trace[2025134654] 'agreement among raft nodes before linearized reading' (duration: 127.047103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:22.807413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.048772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T02:36:22.807459Z","caller":"traceutil/trace.go:171","msg":"trace[420223073] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:6964; }","duration":"104.151036ms","start":"2026-09-18T02:36:22.703296Z","end":"2026-09-18T02:36:22.807447Z","steps":["trace[420223073] 'agreement among raft nodes before linearized reading' (duration: 103.311275ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.942625Z","caller":"traceutil/trace.go:171","msg":"trace[38234156] transaction","detail":"{read_only:false; response_revision:6966; number_of_response:1; }","duration":"124.736116ms","start":"2026-09-18T02:36:22.817847Z","end":"2026-09-18T02:36:22.942583Z","steps":["trace[38234156] 'process raft request' (duration: 86.573192ms)","trace[38234156] 'compare' (duration: 37.957217ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:22.943131Z","caller":"traceutil/trace.go:171","msg":"trace[478576371] transaction","detail":"{read_only:false; response_revision:6968; number_of_response:1; }","duration":"124.835401ms","start":"2026-09-18T02:36:22.818266Z","end":"2026-09-18T02:36:22.943101Z","steps":["trace[478576371] 'process raft request' (duration: 124.630213ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.943248Z","caller":"traceutil/trace.go:171","msg":"trace[1260549745] transaction","detail":"{read_only:false; response_revision:6967; number_of_response:1; }","duration":"125.163533ms","start":"2026-09-18T02:36:22.818055Z","end":"2026-09-18T02:36:22.943218Z","steps":["trace[1260549745] 'process raft request' (duration: 124.446616ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.943388Z","caller":"traceutil/trace.go:171","msg":"trace[1823306387] transaction","detail":"{read_only:false; response_revision:6969; number_of_response:1; }","duration":"122.335479ms","start":"2026-09-18T02:36:22.821034Z","end":"2026-09-18T02:36:22.943369Z","steps":["trace[1823306387] 'process raft request' (duration: 121.952595ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:22.943386Z","caller":"traceutil/trace.go:171","msg":"trace[646487241] linearizableReadLoop","detail":"{readStateIndex:7231; appliedIndex:7226; }","duration":"100.660508ms","start":"2026-09-18T02:36:22.842678Z","end":"2026-09-18T02:36:22.943339Z","steps":["trace[646487241] 'read index received' (duration: 61.661793ms)","trace[646487241] 'applied index is now lower than readState.Index' (duration: 38.993755ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:22.943509Z","caller":"traceutil/trace.go:171","msg":"trace[681045839] transaction","detail":"{read_only:false; response_revision:6970; number_of_response:1; }","duration":"118.78158ms","start":"2026-09-18T02:36:22.824715Z","end":"2026-09-18T02:36:22.943496Z","steps":["trace[681045839] 'process raft request' (duration: 118.326563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:22.943696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.514734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-7686cbb794-fncz4\" ","response":"range_response_count:1 size:8110"} {"level":"info","ts":"2026-09-18T02:36:22.943741Z","caller":"traceutil/trace.go:171","msg":"trace[1988211175] range","detail":"{range_begin:/registry/pods/openstack/horizon-7686cbb794-fncz4; range_end:; response_count:1; response_revision:6970; }","duration":"123.595426ms","start":"2026-09-18T02:36:22.820134Z","end":"2026-09-18T02:36:22.94373Z","steps":["trace[1988211175] 'agreement among raft nodes before linearized reading' (duration: 123.374977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:22.94425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.194397ms","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-09-18T02:36:22.944315Z","caller":"traceutil/trace.go:171","msg":"trace[1580804417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6970; }","duration":"124.311921ms","start":"2026-09-18T02:36:22.81999Z","end":"2026-09-18T02:36:22.944302Z","steps":["trace[1580804417] 'agreement among raft nodes before linearized reading' (duration: 124.146835ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.090593Z","caller":"traceutil/trace.go:171","msg":"trace[674721963] transaction","detail":"{read_only:false; response_revision:6974; number_of_response:1; }","duration":"126.453014ms","start":"2026-09-18T02:36:22.964081Z","end":"2026-09-18T02:36:23.090534Z","steps":["trace[674721963] 'process raft request' (duration: 85.965307ms)","trace[674721963] 'compare' (duration: 40.144644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:36:23.090855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.248091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T02:36:23.090918Z","caller":"traceutil/trace.go:171","msg":"trace[919656161] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6975; }","duration":"113.379626ms","start":"2026-09-18T02:36:22.977523Z","end":"2026-09-18T02:36:23.090903Z","steps":["trace[919656161] 'agreement among raft nodes before linearized reading' (duration: 113.052694ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.855757Z","caller":"traceutil/trace.go:171","msg":"trace[2035461926] transaction","detail":"{read_only:false; response_revision:6988; number_of_response:1; }","duration":"272.634391ms","start":"2026-09-18T02:36:23.583073Z","end":"2026-09-18T02:36:23.855707Z","steps":["trace[2035461926] 'process raft request' (duration: 258.672372ms)","trace[2035461926] 'compare' (duration: 13.72131ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:23.855604Z","caller":"traceutil/trace.go:171","msg":"trace[856487566] linearizableReadLoop","detail":"{readStateIndex:7249; appliedIndex:7248; }","duration":"267.736182ms","start":"2026-09-18T02:36:23.587843Z","end":"2026-09-18T02:36:23.85558Z","steps":["trace[856487566] 'read index received' (duration: 253.880307ms)","trace[856487566] 'applied index is now lower than readState.Index' (duration: 13.854625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:36:23.856005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.136278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:36:23.856415Z","caller":"traceutil/trace.go:171","msg":"trace[1433097377] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:6988; }","duration":"268.594924ms","start":"2026-09-18T02:36:23.587778Z","end":"2026-09-18T02:36:23.856373Z","steps":["trace[1433097377] 'agreement among raft nodes before linearized reading' (duration: 268.119437ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.935054Z","caller":"traceutil/trace.go:171","msg":"trace[2036694855] transaction","detail":"{read_only:false; response_revision:6989; number_of_response:1; }","duration":"226.314102ms","start":"2026-09-18T02:36:23.708684Z","end":"2026-09-18T02:36:23.934998Z","steps":["trace[2036694855] 'process raft request' (duration: 226.015991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:23.935112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.814562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:36:23.935246Z","caller":"traceutil/trace.go:171","msg":"trace[2019614840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6989; }","duration":"296.971629ms","start":"2026-09-18T02:36:23.638215Z","end":"2026-09-18T02:36:23.935187Z","steps":["trace[2019614840] 'agreement among raft nodes before linearized reading' (duration: 296.7322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:23.935495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.322915ms","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:5"} {"level":"info","ts":"2026-09-18T02:36:23.935565Z","caller":"traceutil/trace.go:171","msg":"trace[184214122] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:6989; }","duration":"163.569744ms","start":"2026-09-18T02:36:23.771976Z","end":"2026-09-18T02:36:23.935546Z","steps":["trace[184214122] 'agreement among raft nodes before linearized reading' (duration: 163.296515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:23.936902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.744035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T02:36:23.936958Z","caller":"traceutil/trace.go:171","msg":"trace[1032663024] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:6989; }","duration":"289.817527ms","start":"2026-09-18T02:36:23.647127Z","end":"2026-09-18T02:36:23.936945Z","steps":["trace[1032663024] 'agreement among raft nodes before linearized reading' (duration: 288.480408ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:42.009763Z","caller":"traceutil/trace.go:171","msg":"trace[929460916] transaction","detail":"{read_only:false; response_revision:7139; number_of_response:1; }","duration":"154.644027ms","start":"2026-09-18T02:36:41.855093Z","end":"2026-09-18T02:36:42.009737Z","steps":["trace[929460916] 'process raft request' (duration: 154.494801ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:37:13.569783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4575} {"level":"info","ts":"2026-09-18T02:37:13.62306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4575,"took":"50.416875ms","hash":1211559816,"current-db-size-bytes":41127936,"current-db-size":"41 MB","current-db-size-in-use-bytes":32841728,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-18T02:37:13.623107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1211559816,"revision":4575,"compact-revision":2846}