{"level":"warn","ts":"2026-07-02T15:16:06.240412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:16:06.240541Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-07-02T15:16:06.24073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:16:06.240755Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-02T15:16:06.240792Z","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-07-02T15:16:06.242055Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-07-02T15:16:06.242191Z","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://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-07-02T15:16:06.599877Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"357.290697ms"} {"level":"info","ts":"2026-07-02T15:16:06.840418Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-07-02T15:16:06.840497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T15:16:06.840517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-07-02T15:16:06.840526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T15:16:06.840532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-07-02T15:16:06.840657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-07-02T15:16:06.848401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T15:16:06.850692Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T15:16:06.854359Z","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-07-02T15:16:06.85899Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T15:16:06.86626Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T15:16:06.86768Z","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-07-02T15:16:06.867718Z","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-07-02T15:16:06.867726Z","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-07-02T15:16:06.868166Z","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-07-02T15:16:06.868405Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T15:16:06.868457Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T15:16:06.868623Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-02T15:16:06.868645Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-02T15:16:06.870505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-07-02T15:16:06.870664Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-02T15:16:07.74156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T15:16:07.741621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T15:16:07.741681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-07-02T15:16:07.741701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-07-02T15:16:07.741712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-02T15:16:07.741729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-07-02T15:16:07.741748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-02T15:16:07.742588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:16:07.743229Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T15:16:07.74324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:16:07.743437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:16:07.743548Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T15:16:07.743696Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T15:16:07.743899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:16:07.744013Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:16:07.744062Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:16:07.746604Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T15:16:07.747356Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-07-02T15:16:44.919829Z","caller":"traceutil/trace.go:171","msg":"trace[928754773] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"111.169163ms","start":"2026-07-02T15:16:44.80863Z","end":"2026-07-02T15:16:44.919799Z","steps":["trace[928754773] 'process raft request' (duration: 111.05683ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:16:44.919817Z","caller":"traceutil/trace.go:171","msg":"trace[1275637412] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"117.988804ms","start":"2026-07-02T15:16:44.801792Z","end":"2026-07-02T15:16:44.919781Z","steps":["trace[1275637412] 'process raft request' (duration: 32.60562ms)","trace[1275637412] 'compare' (duration: 85.060244ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:16:45.341519Z","caller":"traceutil/trace.go:171","msg":"trace[1830371510] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"243.259294ms","start":"2026-07-02T15:16:45.098224Z","end":"2026-07-02T15:16:45.341483Z","steps":["trace[1830371510] 'process raft request' (duration: 226.703141ms)","trace[1830371510] 'compare' (duration: 16.255824ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:16:45.344902Z","caller":"traceutil/trace.go:171","msg":"trace[1297547927] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"209.478808ms","start":"2026-07-02T15:16:45.1354Z","end":"2026-07-02T15:16:45.344879Z","steps":["trace[1297547927] 'process raft request' (duration: 209.365535ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:16:45.346061Z","caller":"traceutil/trace.go:171","msg":"trace[1810593354] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"236.258739ms","start":"2026-07-02T15:16:45.109756Z","end":"2026-07-02T15:16:45.346015Z","steps":["trace[1810593354] 'process raft request' (duration: 232.702806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:16:55.757131Z","caller":"traceutil/trace.go:171","msg":"trace[2047969970] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"223.546256ms","start":"2026-07-02T15:16:55.533561Z","end":"2026-07-02T15:16:55.757107Z","steps":["trace[2047969970] 'process raft request' (duration: 223.33037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:16:56.331888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.286498ms","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-07-02T15:16:56.331997Z","caller":"traceutil/trace.go:171","msg":"trace[364069490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:704; }","duration":"465.479324ms","start":"2026-07-02T15:16:55.866498Z","end":"2026-07-02T15:16:56.331978Z","steps":["trace[364069490] 'range keys from in-memory index tree' (duration: 465.155365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:16:56.332041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:16:55.866482Z","time spent":"465.548475ms","remote":"127.0.0.1:35604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:16:56.347462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.886535ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T15:18:30.303147Z","caller":"traceutil/trace.go:171","msg":"trace[1795631910] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"282.125575ms","start":"2026-07-02T15:18:30.020993Z","end":"2026-07-02T15:18:30.303118Z","steps":["trace[1795631910] 'read index received' (duration: 282.100735ms)","trace[1795631910] 'applied index is now lower than readState.Index' (duration: 23.8µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:18:30.303249Z","caller":"traceutil/trace.go:171","msg":"trace[588726732] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"303.336182ms","start":"2026-07-02T15:18:29.999849Z","end":"2026-07-02T15:18:30.303185Z","steps":["trace[588726732] 'process raft request' (duration: 303.138519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:18:30.303523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.39559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-02T15:18:30.303577Z","caller":"traceutil/trace.go:171","msg":"trace[2071688088] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"282.634843ms","start":"2026-07-02T15:18:30.020927Z","end":"2026-07-02T15:18:30.303561Z","steps":["trace[2071688088] 'agreement among raft nodes before linearized reading' (duration: 282.339818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:18:30.303769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.271603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-02T15:18:30.303863Z","caller":"traceutil/trace.go:171","msg":"trace[2085167034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"275.424445ms","start":"2026-07-02T15:18:30.028416Z","end":"2026-07-02T15:18:30.303841Z","steps":["trace[2085167034] 'agreement among raft nodes before linearized reading' (duration: 275.156881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:18:30.303937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:18:29.999829Z","time spent":"303.547596ms","remote":"127.0.0.1:35522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:18:30.429686Z","caller":"traceutil/trace.go:171","msg":"trace[1733552114] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"115.992453ms","start":"2026-07-02T15:18:30.313671Z","end":"2026-07-02T15:18:30.429663Z","steps":["trace[1733552114] 'process raft request' (duration: 113.11631ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:18:30.429944Z","caller":"traceutil/trace.go:171","msg":"trace[1971813794] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"116.144066ms","start":"2026-07-02T15:18:30.313764Z","end":"2026-07-02T15:18:30.429908Z","steps":["trace[1971813794] 'process raft request' (duration: 115.851851ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:18:30.430244Z","caller":"traceutil/trace.go:171","msg":"trace[991410254] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"115.936183ms","start":"2026-07-02T15:18:30.3143Z","end":"2026-07-02T15:18:30.430236Z","steps":["trace[991410254] 'process raft request' (duration: 115.77542ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:18:30.430452Z","caller":"traceutil/trace.go:171","msg":"trace[1685982557] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"114.078534ms","start":"2026-07-02T15:18:30.316285Z","end":"2026-07-02T15:18:30.430363Z","steps":["trace[1685982557] 'process raft request' (duration: 113.900242ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:18:30.432648Z","caller":"traceutil/trace.go:171","msg":"trace[149044641] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"118.552611ms","start":"2026-07-02T15:18:30.314074Z","end":"2026-07-02T15:18:30.432627Z","steps":["trace[149044641] 'process raft request' (duration: 115.744869ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:19:01.237939Z","caller":"traceutil/trace.go:171","msg":"trace[2131509734] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"164.558015ms","start":"2026-07-02T15:19:01.073341Z","end":"2026-07-02T15:19:01.237899Z","steps":["trace[2131509734] 'process raft request' (duration: 124.177174ms)","trace[2131509734] 'compare' (duration: 40.137668ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:19:01.247738Z","caller":"traceutil/trace.go:171","msg":"trace[1126057178] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"117.857458ms","start":"2026-07-02T15:19:01.129852Z","end":"2026-07-02T15:19:01.24771Z","steps":["trace[1126057178] 'process raft request' (duration: 117.803067ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:19:01.247902Z","caller":"traceutil/trace.go:171","msg":"trace[229785869] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"174.310368ms","start":"2026-07-02T15:19:01.073527Z","end":"2026-07-02T15:19:01.247838Z","steps":["trace[229785869] 'process raft request' (duration: 173.914292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:19:01.248086Z","caller":"traceutil/trace.go:171","msg":"trace[793165357] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"174.095845ms","start":"2026-07-02T15:19:01.07393Z","end":"2026-07-02T15:19:01.248026Z","steps":["trace[793165357] 'process raft request' (duration: 173.66519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:19:01.6424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.981066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:19:01.64251Z","caller":"traceutil/trace.go:171","msg":"trace[1863414224] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"117.846618ms","start":"2026-07-02T15:19:01.524646Z","end":"2026-07-02T15:19:01.642493Z","steps":["trace[1863414224] 'read index received' (duration: 625.909µs)","trace[1863414224] 'applied index is now lower than readState.Index' (duration: 117.215589ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:19:01.642557Z","caller":"traceutil/trace.go:171","msg":"trace[1457042797] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"203.81891ms","start":"2026-07-02T15:19:01.438708Z","end":"2026-07-02T15:19:01.642527Z","steps":["trace[1457042797] 'process raft request' (duration: 86.612292ms)","trace[1457042797] 'compare' (duration: 116.532969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:19:01.64306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.403495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-02T15:19:01.643112Z","caller":"traceutil/trace.go:171","msg":"trace[1238397241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"118.470596ms","start":"2026-07-02T15:19:01.524627Z","end":"2026-07-02T15:19:01.643097Z","steps":["trace[1238397241] 'agreement among raft nodes before linearized reading' (duration: 117.953419ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:19:01.860574Z","caller":"traceutil/trace.go:171","msg":"trace[1916825255] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"212.935345ms","start":"2026-07-02T15:19:01.64761Z","end":"2026-07-02T15:19:01.860545Z","steps":["trace[1916825255] 'process raft request' (duration: 154.366405ms)","trace[1916825255] 'compare' (duration: 58.190545ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:20:47.92533Z","caller":"traceutil/trace.go:171","msg":"trace[1365794377] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"134.299994ms","start":"2026-07-02T15:20:47.791007Z","end":"2026-07-02T15:20:47.925307Z","steps":["trace[1365794377] 'process raft request' (duration: 75.672562ms)","trace[1365794377] 'compare' (duration: 58.472007ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:20:47.925642Z","caller":"traceutil/trace.go:171","msg":"trace[761857842] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"133.316531ms","start":"2026-07-02T15:20:47.792285Z","end":"2026-07-02T15:20:47.925602Z","steps":["trace[761857842] 'process raft request' (duration: 132.98621ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:20:47.925796Z","caller":"traceutil/trace.go:171","msg":"trace[323896129] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"132.243895ms","start":"2026-07-02T15:20:47.793541Z","end":"2026-07-02T15:20:47.925785Z","steps":["trace[323896129] 'process raft request' (duration: 131.933804ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:24.790708Z","caller":"traceutil/trace.go:171","msg":"trace[524778085] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"163.930688ms","start":"2026-07-02T15:24:24.626754Z","end":"2026-07-02T15:24:24.790684Z","steps":["trace[524778085] 'process raft request' (duration: 163.774316ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:24.795379Z","caller":"traceutil/trace.go:171","msg":"trace[491552042] linearizableReadLoop","detail":"{readStateIndex:4695; appliedIndex:4693; }","duration":"164.894896ms","start":"2026-07-02T15:24:24.630462Z","end":"2026-07-02T15:24:24.795357Z","steps":["trace[491552042] 'read index received' (duration: 160.097797ms)","trace[491552042] 'applied index is now lower than readState.Index' (duration: 4.796309ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:24:24.795457Z","caller":"traceutil/trace.go:171","msg":"trace[277776308] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"165.483206ms","start":"2026-07-02T15:24:24.629954Z","end":"2026-07-02T15:24:24.795437Z","steps":["trace[277776308] 'process raft request' (duration: 165.264192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:24.795545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.070679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"warn","ts":"2026-07-02T15:24:24.79555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.077767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:24:24.795573Z","caller":"traceutil/trace.go:171","msg":"trace[1027503818] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4526; }","duration":"165.13ms","start":"2026-07-02T15:24:24.630432Z","end":"2026-07-02T15:24:24.795562Z","steps":["trace[1027503818] 'agreement among raft nodes before linearized reading' (duration: 165.020267ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:24.795607Z","caller":"traceutil/trace.go:171","msg":"trace[1945858205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4526; }","duration":"146.149618ms","start":"2026-07-02T15:24:24.649443Z","end":"2026-07-02T15:24:24.795593Z","steps":["trace[1945858205] 'agreement among raft nodes before linearized reading' (duration: 146.049706ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:32.10277Z","caller":"traceutil/trace.go:171","msg":"trace[953231562] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"108.535059ms","start":"2026-07-02T15:24:31.994209Z","end":"2026-07-02T15:24:32.102744Z","steps":["trace[953231562] 'process raft request' (duration: 108.345466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:32.396872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.349522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:24:32.397025Z","caller":"traceutil/trace.go:171","msg":"trace[534760312] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"137.804016ms","start":"2026-07-02T15:24:32.259206Z","end":"2026-07-02T15:24:32.39701Z","steps":["trace[534760312] 'process raft request' (duration: 137.753225ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:32.397314Z","caller":"traceutil/trace.go:171","msg":"trace[444968113] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4843; }","duration":"371.403937ms","start":"2026-07-02T15:24:32.025884Z","end":"2026-07-02T15:24:32.397288Z","steps":["trace[444968113] 'read index received' (duration: 76.693295ms)","trace[444968113] 'applied index is now lower than readState.Index' (duration: 294.707902ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:24:32.397441Z","caller":"traceutil/trace.go:171","msg":"trace[1806670952] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"393.87819ms","start":"2026-07-02T15:24:32.00355Z","end":"2026-07-02T15:24:32.397429Z","steps":["trace[1806670952] 'process raft request' (duration: 132.857515ms)","trace[1806670952] 'compare' (duration: 260.261091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:24:32.397511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:24:32.003529Z","time spent":"393.945491ms","remote":"127.0.0.1:35604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:24:32.397516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.609472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:24:32.397589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.076246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:24:32.39762Z","caller":"traceutil/trace.go:171","msg":"trace[974977289] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4670; }","duration":"345.128116ms","start":"2026-07-02T15:24:32.052477Z","end":"2026-07-02T15:24:32.397605Z","steps":["trace[974977289] 'agreement among raft nodes before linearized reading' (duration: 345.071975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:32.397644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:24:32.052463Z","time spent":"345.175237ms","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:24:32.397644Z","caller":"traceutil/trace.go:171","msg":"trace[811981818] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4670; }","duration":"371.752684ms","start":"2026-07-02T15:24:32.025848Z","end":"2026-07-02T15:24:32.397601Z","steps":["trace[811981818] 'agreement among raft nodes before linearized reading' (duration: 371.595281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:32.397773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:24:32.025836Z","time spent":"371.890746ms","remote":"127.0.0.1:59400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:24:32.397929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.8355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T15:24:32.397968Z","caller":"traceutil/trace.go:171","msg":"trace[18342456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4670; }","duration":"159.899821ms","start":"2026-07-02T15:24:32.238059Z","end":"2026-07-02T15:24:32.397959Z","steps":["trace[18342456] 'agreement among raft nodes before linearized reading' (duration: 159.782999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:32.398836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.056124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-07-02T15:24:32.39892Z","caller":"traceutil/trace.go:171","msg":"trace[243353399] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4670; }","duration":"202.194467ms","start":"2026-07-02T15:24:32.196708Z","end":"2026-07-02T15:24:32.398902Z","steps":["trace[243353399] 'agreement among raft nodes before linearized reading' (duration: 201.117877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:38.454752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.868411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-07-02T15:24:38.454824Z","caller":"traceutil/trace.go:171","msg":"trace[425324515] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4767; }","duration":"234.986043ms","start":"2026-07-02T15:24:38.219823Z","end":"2026-07-02T15:24:38.454809Z","steps":["trace[425324515] 'range keys from in-memory index tree' (duration: 234.053776ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:39.022558Z","caller":"traceutil/trace.go:171","msg":"trace[700854986] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"208.959259ms","start":"2026-07-02T15:24:38.813578Z","end":"2026-07-02T15:24:39.022537Z","steps":["trace[700854986] 'process raft request' (duration: 171.858935ms)","trace[700854986] 'compare' (duration: 37.010803ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:24:39.030657Z","caller":"traceutil/trace.go:171","msg":"trace[1810281233] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4951; }","duration":"198.956668ms","start":"2026-07-02T15:24:38.831688Z","end":"2026-07-02T15:24:39.030645Z","steps":["trace[1810281233] 'read index received' (duration: 153.683915ms)","trace[1810281233] 'applied index is now lower than readState.Index' (duration: 45.272113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:24:39.030758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.053159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:24:39.030784Z","caller":"traceutil/trace.go:171","msg":"trace[1387631705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"199.10658ms","start":"2026-07-02T15:24:38.83167Z","end":"2026-07-02T15:24:39.030777Z","steps":["trace[1387631705] 'agreement among raft nodes before linearized reading' (duration: 199.052789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:39.03094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.565549ms","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-07-02T15:24:39.03099Z","caller":"traceutil/trace.go:171","msg":"trace[1212430546] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4776; }","duration":"171.642701ms","start":"2026-07-02T15:24:38.859339Z","end":"2026-07-02T15:24:39.030982Z","steps":["trace[1212430546] 'agreement among raft nodes before linearized reading' (duration: 171.58089ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:39.030925Z","caller":"traceutil/trace.go:171","msg":"trace[602217495] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"207.167416ms","start":"2026-07-02T15:24:38.823737Z","end":"2026-07-02T15:24:39.030904Z","steps":["trace[602217495] 'process raft request' (duration: 206.784609ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:39.030893Z","caller":"traceutil/trace.go:171","msg":"trace[461890873] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"187.967558ms","start":"2026-07-02T15:24:38.842777Z","end":"2026-07-02T15:24:39.030745Z","steps":["trace[461890873] 'process raft request' (duration: 187.830095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:39.031115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.658127ms","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-07-02T15:24:39.031135Z","caller":"traceutil/trace.go:171","msg":"trace[356524937] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4776; }","duration":"129.702459ms","start":"2026-07-02T15:24:38.901427Z","end":"2026-07-02T15:24:39.031129Z","steps":["trace[356524937] 'agreement among raft nodes before linearized reading' (duration: 129.649358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:39.031288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.305392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-02T15:24:39.031368Z","caller":"traceutil/trace.go:171","msg":"trace[226186128] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4776; }","duration":"156.396153ms","start":"2026-07-02T15:24:38.87493Z","end":"2026-07-02T15:24:39.031326Z","steps":["trace[226186128] 'agreement among raft nodes before linearized reading' (duration: 156.20713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:39.141882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.736458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-02T15:24:39.143Z","caller":"traceutil/trace.go:171","msg":"trace[1821473665] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4782; }","duration":"102.876678ms","start":"2026-07-02T15:24:39.040108Z","end":"2026-07-02T15:24:39.142985Z","steps":["trace[1821473665] 'agreement among raft nodes before linearized reading' (duration: 99.467216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:39.14282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.697205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-07-02T15:24:39.143383Z","caller":"traceutil/trace.go:171","msg":"trace[720492888] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4782; }","duration":"103.253655ms","start":"2026-07-02T15:24:39.040083Z","end":"2026-07-02T15:24:39.143337Z","steps":["trace[720492888] 'agreement among raft nodes before linearized reading' (duration: 99.537848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:53.158535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.053644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:24:53.158755Z","caller":"traceutil/trace.go:171","msg":"trace[1766422933] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"331.243958ms","start":"2026-07-02T15:24:52.827464Z","end":"2026-07-02T15:24:53.158708Z","steps":["trace[1766422933] 'process raft request' (duration: 140.667264ms)","trace[1766422933] 'compare' (duration: 189.917392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:24:53.158839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.525558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:24:53.158875Z","caller":"traceutil/trace.go:171","msg":"trace[1721333493] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4957; }","duration":"272.60309ms","start":"2026-07-02T15:24:52.886261Z","end":"2026-07-02T15:24:53.158864Z","steps":["trace[1721333493] 'agreement among raft nodes before linearized reading' (duration: 272.525198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:53.158906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:24:52.827438Z","time spent":"331.391111ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:24:53.158747Z","caller":"traceutil/trace.go:171","msg":"trace[1940658510] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"272.418416ms","start":"2026-07-02T15:24:52.886305Z","end":"2026-07-02T15:24:53.158723Z","steps":["trace[1940658510] 'read index received' (duration: 81.746731ms)","trace[1940658510] 'applied index is now lower than readState.Index' (duration: 190.670455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:24:53.169384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.003757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-07-02T15:24:53.169719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.452136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:24:53.169769Z","caller":"traceutil/trace.go:171","msg":"trace[59339399] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4958; }","duration":"141.550797ms","start":"2026-07-02T15:24:53.028203Z","end":"2026-07-02T15:24:53.169754Z","steps":["trace[59339399] 'agreement among raft nodes before linearized reading' (duration: 141.461345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:24:53.169864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.172888ms","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-07-02T15:24:53.169452Z","caller":"traceutil/trace.go:171","msg":"trace[1913566018] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4958; }","duration":"240.115729ms","start":"2026-07-02T15:24:52.929324Z","end":"2026-07-02T15:24:53.16944Z","steps":["trace[1913566018] 'agreement among raft nodes before linearized reading' (duration: 239.532879ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:24:53.169934Z","caller":"traceutil/trace.go:171","msg":"trace[561510706] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4958; }","duration":"222.27721ms","start":"2026-07-02T15:24:52.947643Z","end":"2026-07-02T15:24:53.16992Z","steps":["trace[561510706] 'agreement among raft nodes before linearized reading' (duration: 222.100857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:34.953398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.991608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:25:34.953462Z","caller":"traceutil/trace.go:171","msg":"trace[1364481136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5318; }","duration":"119.09031ms","start":"2026-07-02T15:25:34.834358Z","end":"2026-07-02T15:25:34.953448Z","steps":["trace[1364481136] 'range keys from in-memory index tree' (duration: 118.927736ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:07.770467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2900} {"level":"info","ts":"2026-07-02T15:26:07.856634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2900,"took":"84.853996ms","hash":2549937161,"current-db-size-bytes":53383168,"current-db-size":"53 MB","current-db-size-in-use-bytes":29327360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-02T15:26:07.856702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2549937161,"revision":2900,"compact-revision":-1} {"level":"info","ts":"2026-07-02T15:29:04.614888Z","caller":"traceutil/trace.go:171","msg":"trace[1449591694] transaction","detail":"{read_only:false; response_revision:6786; number_of_response:1; }","duration":"109.010366ms","start":"2026-07-02T15:29:04.50586Z","end":"2026-07-02T15:29:04.61487Z","steps":["trace[1449591694] 'process raft request' (duration: 108.895671ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:31:07.778238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5575} {"level":"info","ts":"2026-07-02T15:31:07.886863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5575,"took":"107.468803ms","hash":1761954987,"current-db-size-bytes":53383168,"current-db-size":"53 MB","current-db-size-in-use-bytes":18612224,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-02T15:31:07.886929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1761954987,"revision":5575,"compact-revision":2900}