{"level":"warn","ts":"2026-03-05T04:58:59.787109Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T04:58:59.787235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-03-05T04:58:59.787305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T04:58:59.787317Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-05T04:58:59.787339Z","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-03-05T04:58:59.787972Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-03-05T04:58:59.788045Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-03-05T04:58:59.792012Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.754248ms"} {"level":"info","ts":"2026-03-05T04:58:59.80369Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-03-05T04:58:59.803803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T04:58:59.803828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-03-05T04:58:59.803843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T04:58:59.803862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-03-05T04:58:59.803923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-03-05T04:58:59.81062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T04:58:59.81233Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T04:58:59.813933Z","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-03-05T04:58:59.81642Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T04:58:59.817605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T04:58:59.817766Z","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-03-05T04:58:59.817854Z","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-03-05T04:58:59.817902Z","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-03-05T04:58:59.820327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-03-05T04:58:59.8205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-05T04:58:59.822356Z","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-03-05T04:58:59.822548Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-05T04:58:59.822661Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T04:58:59.82268Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-05T04:58:59.822631Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T04:58:59.904349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T04:58:59.904398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T04:58:59.904417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-03-05T04:58:59.904429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-03-05T04:58:59.904437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-05T04:58:59.904445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-03-05T04:58:59.904453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-05T04:58:59.912459Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T04:58:59.912467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T04:58:59.912503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T04:58:59.912524Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:58:59.913774Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:58:59.913875Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:58:59.913912Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:58:59.914425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-03-05T04:58:59.915044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T04:58:59.916364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T04:58:59.916442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T04:59:22.743937Z","caller":"traceutil/trace.go:171","msg":"trace[2075214887] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"121.754937ms","start":"2026-03-05T04:59:22.622156Z","end":"2026-03-05T04:59:22.743911Z","steps":["trace[2075214887] 'process raft request' (duration: 121.488771ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:59:28.073151Z","caller":"traceutil/trace.go:171","msg":"trace[808566144] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"104.976055ms","start":"2026-03-05T04:59:27.96815Z","end":"2026-03-05T04:59:28.073126Z","steps":["trace[808566144] 'process raft request' (duration: 104.78301ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:59:36.01004Z","caller":"traceutil/trace.go:171","msg":"trace[385256890] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"146.193679ms","start":"2026-03-05T04:59:35.863822Z","end":"2026-03-05T04:59:36.010015Z","steps":["trace[385256890] 'read index received' (duration: 145.994324ms)","trace[385256890] 'applied index is now lower than readState.Index' (duration: 198.295µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:59:36.010161Z","caller":"traceutil/trace.go:171","msg":"trace[1005338917] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"193.255619ms","start":"2026-03-05T04:59:35.816876Z","end":"2026-03-05T04:59:36.010131Z","steps":["trace[1005338917] 'process raft request' (duration: 192.976043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:59:36.01032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.476665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t85nk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-05T04:59:36.010396Z","caller":"traceutil/trace.go:171","msg":"trace[1039095681] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t85nk; range_end:; response_count:1; response_revision:550; }","duration":"146.614098ms","start":"2026-03-05T04:59:35.863772Z","end":"2026-03-05T04:59:36.010386Z","steps":["trace[1039095681] 'agreement among raft nodes before linearized reading' (duration: 146.345302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:59:36.322882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.555704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T04:59:36.323002Z","caller":"traceutil/trace.go:171","msg":"trace[1495880166] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"232.439766ms","start":"2026-03-05T04:59:36.090545Z","end":"2026-03-05T04:59:36.322985Z","steps":["trace[1495880166] 'read index received' (duration: 86.237157ms)","trace[1495880166] 'applied index is now lower than readState.Index' (duration: 146.201289ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:59:36.323063Z","caller":"traceutil/trace.go:171","msg":"trace[685229063] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"298.402518ms","start":"2026-03-05T04:59:36.024614Z","end":"2026-03-05T04:59:36.323016Z","steps":["trace[685229063] 'process raft request' (duration: 152.153788ms)","trace[685229063] 'compare' (duration: 145.39139ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T04:59:36.32313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.583709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:59:36.323178Z","caller":"traceutil/trace.go:171","msg":"trace[1008446771] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:551; }","duration":"232.667181ms","start":"2026-03-05T04:59:36.090494Z","end":"2026-03-05T04:59:36.323161Z","steps":["trace[1008446771] 'agreement among raft nodes before linearized reading' (duration: 232.59093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:59:36.323205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.256604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:59:36.323284Z","caller":"traceutil/trace.go:171","msg":"trace[450843916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"221.410388ms","start":"2026-03-05T04:59:36.101856Z","end":"2026-03-05T04:59:36.323267Z","steps":["trace[450843916] 'agreement among raft nodes before linearized reading' (duration: 221.278845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:59:36.323383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.871175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-05T04:59:36.323431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.439262ms","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-03-05T04:59:36.323461Z","caller":"traceutil/trace.go:171","msg":"trace[1612476384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"208.994328ms","start":"2026-03-05T04:59:36.114448Z","end":"2026-03-05T04:59:36.323442Z","steps":["trace[1612476384] 'agreement among raft nodes before linearized reading' (duration: 208.808314ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:59:36.323505Z","caller":"traceutil/trace.go:171","msg":"trace[1832182273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"157.560705ms","start":"2026-03-05T04:59:36.165926Z","end":"2026-03-05T04:59:36.323487Z","steps":["trace[1832182273] 'agreement among raft nodes before linearized reading' (duration: 157.328609ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:59:36.584901Z","caller":"traceutil/trace.go:171","msg":"trace[1616504321] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"256.505088ms","start":"2026-03-05T04:59:36.328159Z","end":"2026-03-05T04:59:36.584664Z","steps":["trace[1616504321] 'process raft request' (duration: 170.263792ms)","trace[1616504321] 'compare' (duration: 85.619891ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T04:59:55.267497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.921596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:59:55.267652Z","caller":"traceutil/trace.go:171","msg":"trace[1202676372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"164.133641ms","start":"2026-03-05T04:59:55.103497Z","end":"2026-03-05T04:59:55.267631Z","steps":["trace[1202676372] 'range keys from in-memory index tree' (duration: 163.801234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:00:43.589413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.426347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T05:00:43.589506Z","caller":"traceutil/trace.go:171","msg":"trace[551703921] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1591; }","duration":"101.596191ms","start":"2026-03-05T05:00:43.487887Z","end":"2026-03-05T05:00:43.589483Z","steps":["trace[551703921] 'count revisions from in-memory index tree' (duration: 101.307444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:00:43.98737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.188403ms","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-03-05T05:00:43.987492Z","caller":"traceutil/trace.go:171","msg":"trace[507392828] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1593; }","duration":"162.348007ms","start":"2026-03-05T05:00:43.825096Z","end":"2026-03-05T05:00:43.987444Z","steps":["trace[507392828] 'range keys from in-memory index tree' (duration: 161.985089ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:00:56.261191Z","caller":"traceutil/trace.go:171","msg":"trace[248410212] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1753; }","duration":"178.555735ms","start":"2026-03-05T05:00:56.082612Z","end":"2026-03-05T05:00:56.261168Z","steps":["trace[248410212] 'read index received' (duration: 178.313219ms)","trace[248410212] 'applied index is now lower than readState.Index' (duration: 241.276µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:00:56.261496Z","caller":"traceutil/trace.go:171","msg":"trace[1654338507] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"198.365366ms","start":"2026-03-05T05:00:56.063118Z","end":"2026-03-05T05:00:56.261483Z","steps":["trace[1654338507] 'process raft request' (duration: 197.913906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:00:56.261723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.082678ms","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-03-05T05:00:56.261812Z","caller":"traceutil/trace.go:171","msg":"trace[2017774586] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1687; }","duration":"179.226582ms","start":"2026-03-05T05:00:56.082564Z","end":"2026-03-05T05:00:56.261791Z","steps":["trace[2017774586] 'agreement among raft nodes before linearized reading' (duration: 178.964404ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:01:34.156922Z","caller":"traceutil/trace.go:171","msg":"trace[643946260] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"226.378103ms","start":"2026-03-05T05:01:33.930515Z","end":"2026-03-05T05:01:34.156893Z","steps":["trace[643946260] 'process raft request' (duration: 226.183479ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:01:34.169116Z","caller":"traceutil/trace.go:171","msg":"trace[1520684201] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"237.508518ms","start":"2026-03-05T05:01:33.93159Z","end":"2026-03-05T05:01:34.169099Z","steps":["trace[1520684201] 'process raft request' (duration: 237.441257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:01:34.826389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.74512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T05:01:34.826511Z","caller":"traceutil/trace.go:171","msg":"trace[114939395] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1964; }","duration":"335.96759ms","start":"2026-03-05T05:01:34.490524Z","end":"2026-03-05T05:01:34.826492Z","steps":["trace[114939395] 'read index received' (duration: 3.909403ms)","trace[114939395] 'applied index is now lower than readState.Index' (duration: 332.056587ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:01:34.826536Z","caller":"traceutil/trace.go:171","msg":"trace[39157103] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"355.472673ms","start":"2026-03-05T05:01:34.471042Z","end":"2026-03-05T05:01:34.826515Z","steps":["trace[39157103] 'process raft request' (duration: 155.494308ms)","trace[39157103] 'compare' (duration: 199.546515ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:01:34.826615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:01:34.471027Z","time spent":"355.549016ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T05:01:34.826782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.256796ms","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-03-05T05:01:34.826835Z","caller":"traceutil/trace.go:171","msg":"trace[1572247993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1890; }","duration":"336.348918ms","start":"2026-03-05T05:01:34.490471Z","end":"2026-03-05T05:01:34.82682Z","steps":["trace[1572247993] 'agreement among raft nodes before linearized reading' (duration: 336.125683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:01:34.826819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.535765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-05T05:01:34.826881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:01:34.490443Z","time spent":"336.426271ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T05:01:34.826909Z","caller":"traceutil/trace.go:171","msg":"trace[2121557100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1890; }","duration":"167.678818ms","start":"2026-03-05T05:01:34.659208Z","end":"2026-03-05T05:01:34.826886Z","steps":["trace[2121557100] 'agreement among raft nodes before linearized reading' (duration: 167.458753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:01:34.826905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.207767ms","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-03-05T05:01:34.827019Z","caller":"traceutil/trace.go:171","msg":"trace[224097184] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"289.363181ms","start":"2026-03-05T05:01:34.537627Z","end":"2026-03-05T05:01:34.82699Z","steps":["trace[224097184] 'agreement among raft nodes before linearized reading' (duration: 289.024824ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:01:35.089838Z","caller":"traceutil/trace.go:171","msg":"trace[801704122] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"258.057216ms","start":"2026-03-05T05:01:34.831742Z","end":"2026-03-05T05:01:35.089799Z","steps":["trace[801704122] 'process raft request' (duration: 165.160947ms)","trace[801704122] 'compare' (duration: 92.552271ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:01:35.113922Z","caller":"traceutil/trace.go:171","msg":"trace[291088968] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1966; }","duration":"187.052499ms","start":"2026-03-05T05:01:34.926853Z","end":"2026-03-05T05:01:35.113906Z","steps":["trace[291088968] 'read index received' (duration: 70.066217ms)","trace[291088968] 'applied index is now lower than readState.Index' (duration: 116.984882ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:01:35.113979Z","caller":"traceutil/trace.go:171","msg":"trace[966322972] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"281.778401ms","start":"2026-03-05T05:01:34.832168Z","end":"2026-03-05T05:01:35.113947Z","steps":["trace[966322972] 'process raft request' (duration: 281.641817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:01:35.114113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.54195ms","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":"warn","ts":"2026-03-05T05:01:35.114139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.278474ms","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-03-05T05:01:35.114189Z","caller":"traceutil/trace.go:171","msg":"trace[820196758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1892; }","duration":"187.356045ms","start":"2026-03-05T05:01:34.926818Z","end":"2026-03-05T05:01:35.114174Z","steps":["trace[820196758] 'agreement among raft nodes before linearized reading' (duration: 187.167631ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:01:35.114151Z","caller":"traceutil/trace.go:171","msg":"trace[993184702] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1892; }","duration":"158.673733ms","start":"2026-03-05T05:01:34.955467Z","end":"2026-03-05T05:01:35.114141Z","steps":["trace[993184702] 'agreement among raft nodes before linearized reading' (duration: 158.550261ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:02:29.718439Z","caller":"traceutil/trace.go:171","msg":"trace[1940593495] linearizableReadLoop","detail":"{readStateIndex:2428; appliedIndex:2427; }","duration":"114.855386ms","start":"2026-03-05T05:02:29.603556Z","end":"2026-03-05T05:02:29.718412Z","steps":["trace[1940593495] 'read index received' (duration: 114.715962ms)","trace[1940593495] 'applied index is now lower than readState.Index' (duration: 138.364µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:02:29.718544Z","caller":"traceutil/trace.go:171","msg":"trace[2104267488] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"128.693125ms","start":"2026-03-05T05:02:29.589812Z","end":"2026-03-05T05:02:29.718505Z","steps":["trace[2104267488] 'process raft request' (duration: 128.426508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:02:29.718644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.982318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T05:02:29.71869Z","caller":"traceutil/trace.go:171","msg":"trace[1697063528] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2332; }","duration":"115.078831ms","start":"2026-03-05T05:02:29.6036Z","end":"2026-03-05T05:02:29.718678Z","steps":["trace[1697063528] 'agreement among raft nodes before linearized reading' (duration: 114.977699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:02:29.718688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.102462ms","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-03-05T05:02:29.718792Z","caller":"traceutil/trace.go:171","msg":"trace[2089988281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2332; }","duration":"115.219044ms","start":"2026-03-05T05:02:29.60355Z","end":"2026-03-05T05:02:29.718769Z","steps":["trace[2089988281] 'agreement among raft nodes before linearized reading' (duration: 115.013279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:03:03.622948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.681838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T05:03:03.62306Z","caller":"traceutil/trace.go:171","msg":"trace[942347344] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2618; }","duration":"144.63764ms","start":"2026-03-05T05:03:03.478406Z","end":"2026-03-05T05:03:03.623043Z","steps":["trace[942347344] 'read index received' (duration: 116.613µs)","trace[942347344] 'applied index is now lower than readState.Index' (duration: 144.519967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:03:03.623257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.856564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-05T05:03:03.623294Z","caller":"traceutil/trace.go:171","msg":"trace[243595253] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2516; }","duration":"144.936806ms","start":"2026-03-05T05:03:03.478347Z","end":"2026-03-05T05:03:03.623284Z","steps":["trace[243595253] 'agreement among raft nodes before linearized reading' (duration: 144.781143ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:03:03.623265Z","caller":"traceutil/trace.go:171","msg":"trace[1716507971] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"306.234382ms","start":"2026-03-05T05:03:03.316993Z","end":"2026-03-05T05:03:03.623227Z","steps":["trace[1716507971] 'process raft request' (duration: 118.171025ms)","trace[1716507971] 'compare' (duration: 187.519074ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:03:03.623475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.39805ms","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":"warn","ts":"2026-03-05T05:03:03.623498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:03:03.316969Z","time spent":"306.415247ms","remote":"127.0.0.1:58518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T05:03:03.623512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.150347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-05T05:03:03.623511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.146144ms","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-03-05T05:03:03.623553Z","caller":"traceutil/trace.go:171","msg":"trace[1351518676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2516; }","duration":"107.493692ms","start":"2026-03-05T05:03:03.516041Z","end":"2026-03-05T05:03:03.623535Z","steps":["trace[1351518676] 'agreement among raft nodes before linearized reading' (duration: 107.258546ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:03:03.623585Z","caller":"traceutil/trace.go:171","msg":"trace[45147211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2516; }","duration":"105.248789ms","start":"2026-03-05T05:03:03.518322Z","end":"2026-03-05T05:03:03.623571Z","steps":["trace[45147211] 'agreement among raft nodes before linearized reading' (duration: 105.023544ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:03:03.62361Z","caller":"traceutil/trace.go:171","msg":"trace[1872143119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2516; }","duration":"104.275176ms","start":"2026-03-05T05:03:03.519315Z","end":"2026-03-05T05:03:03.623591Z","steps":["trace[1872143119] 'agreement among raft nodes before linearized reading' (duration: 104.006499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:05:29.171406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.037641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T05:05:29.171731Z","caller":"traceutil/trace.go:171","msg":"trace[1850972716] transaction","detail":"{read_only:false; response_revision:3417; number_of_response:1; }","duration":"219.824454ms","start":"2026-03-05T05:05:28.951682Z","end":"2026-03-05T05:05:29.171506Z","steps":["trace[1850972716] 'process raft request' (duration: 86.491506ms)","trace[1850972716] 'compare' (duration: 132.883658ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:06:44.117756Z","caller":"traceutil/trace.go:171","msg":"trace[337985632] transaction","detail":"{read_only:false; response_revision:4255; number_of_response:1; }","duration":"116.635533ms","start":"2026-03-05T05:06:44.001093Z","end":"2026-03-05T05:06:44.117729Z","steps":["trace[337985632] 'process raft request' (duration: 116.376357ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:06:44.118522Z","caller":"traceutil/trace.go:171","msg":"trace[729582389] transaction","detail":"{read_only:false; response_revision:4256; number_of_response:1; }","duration":"113.255953ms","start":"2026-03-05T05:06:44.004551Z","end":"2026-03-05T05:06:44.117807Z","steps":["trace[729582389] 'process raft request' (duration: 113.091199ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:06:53.536822Z","caller":"traceutil/trace.go:171","msg":"trace[1326798731] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"106.052433ms","start":"2026-03-05T05:06:53.430741Z","end":"2026-03-05T05:06:53.536793Z","steps":["trace[1326798731] 'process raft request' (duration: 105.889439ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:07:11.400633Z","caller":"traceutil/trace.go:171","msg":"trace[349806895] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"116.576771ms","start":"2026-03-05T05:07:11.284029Z","end":"2026-03-05T05:07:11.400605Z","steps":["trace[349806895] 'process raft request' (duration: 116.184691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:07:11.626938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.789213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T05:07:11.627041Z","caller":"traceutil/trace.go:171","msg":"trace[775537919] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4922; }","duration":"129.987817ms","start":"2026-03-05T05:07:11.49703Z","end":"2026-03-05T05:07:11.627018Z","steps":["trace[775537919] 'count revisions from in-memory index tree' (duration: 129.65708ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:07:23.592569Z","caller":"traceutil/trace.go:171","msg":"trace[807148530] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"105.33785ms","start":"2026-03-05T05:07:23.487203Z","end":"2026-03-05T05:07:23.592541Z","steps":["trace[807148530] 'process raft request' (duration: 64.475704ms)","trace[807148530] 'compare' (duration: 40.726353ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:09:00.210681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2800} {"level":"info","ts":"2026-03-05T05:09:00.266761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2800,"took":"54.254803ms","hash":3454841,"current-db-size-bytes":42205184,"current-db-size":"42 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-05T05:09:00.266814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3454841,"revision":2800,"compact-revision":-1} {"level":"info","ts":"2026-03-05T05:13:17.376014Z","caller":"traceutil/trace.go:171","msg":"trace[740653708] linearizableReadLoop","detail":"{readStateIndex:7856; appliedIndex:7855; }","duration":"271.49101ms","start":"2026-03-05T05:13:17.104499Z","end":"2026-03-05T05:13:17.37599Z","steps":["trace[740653708] 'read index received' (duration: 271.338237ms)","trace[740653708] 'applied index is now lower than readState.Index' (duration: 151.683µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:13:17.37616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.647814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T05:13:17.376136Z","caller":"traceutil/trace.go:171","msg":"trace[543350917] transaction","detail":"{read_only:false; response_revision:7578; number_of_response:1; }","duration":"396.754717ms","start":"2026-03-05T05:13:16.97935Z","end":"2026-03-05T05:13:17.376105Z","steps":["trace[543350917] 'process raft request' (duration: 396.493111ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:17.376202Z","caller":"traceutil/trace.go:171","msg":"trace[107044612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7578; }","duration":"271.740726ms","start":"2026-03-05T05:13:17.10445Z","end":"2026-03-05T05:13:17.376191Z","steps":["trace[107044612] 'agreement among raft nodes before linearized reading' (duration: 271.654184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:13:17.376425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:13:16.979323Z","time spent":"396.89421ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T05:13:17.377302Z","caller":"traceutil/trace.go:171","msg":"trace[1198109554] transaction","detail":"{read_only:false; response_revision:7579; number_of_response:1; }","duration":"267.814243ms","start":"2026-03-05T05:13:17.10946Z","end":"2026-03-05T05:13:17.377274Z","steps":["trace[1198109554] 'process raft request' (duration: 267.67355ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:28.925918Z","caller":"traceutil/trace.go:171","msg":"trace[559158876] transaction","detail":"{read_only:false; response_revision:7630; number_of_response:1; }","duration":"164.504584ms","start":"2026-03-05T05:13:28.761383Z","end":"2026-03-05T05:13:28.925888Z","steps":["trace[559158876] 'process raft request' (duration: 164.247668ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:28.98391Z","caller":"traceutil/trace.go:171","msg":"trace[1887206863] transaction","detail":"{read_only:false; response_revision:7631; number_of_response:1; }","duration":"220.815023ms","start":"2026-03-05T05:13:28.763074Z","end":"2026-03-05T05:13:28.983889Z","steps":["trace[1887206863] 'process raft request' (duration: 220.620598ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:28.984235Z","caller":"traceutil/trace.go:171","msg":"trace[1038952499] linearizableReadLoop","detail":"{readStateIndex:7915; appliedIndex:7911; }","duration":"177.91996ms","start":"2026-03-05T05:13:28.806287Z","end":"2026-03-05T05:13:28.984207Z","steps":["trace[1038952499] 'read index received' (duration: 119.326367ms)","trace[1038952499] 'applied index is now lower than readState.Index' (duration: 58.592163ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:13:28.98432Z","caller":"traceutil/trace.go:171","msg":"trace[1709512767] transaction","detail":"{read_only:false; response_revision:7633; number_of_response:1; }","duration":"220.178189ms","start":"2026-03-05T05:13:28.764082Z","end":"2026-03-05T05:13:28.98426Z","steps":["trace[1709512767] 'process raft request' (duration: 220.013635ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:28.984434Z","caller":"traceutil/trace.go:171","msg":"trace[949783755] transaction","detail":"{read_only:false; response_revision:7632; number_of_response:1; }","duration":"221.005208ms","start":"2026-03-05T05:13:28.763349Z","end":"2026-03-05T05:13:28.984355Z","steps":["trace[949783755] 'process raft request' (duration: 220.494336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:13:28.984753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.390252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T05:13:28.985248Z","caller":"traceutil/trace.go:171","msg":"trace[528038454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7633; }","duration":"178.984526ms","start":"2026-03-05T05:13:28.806251Z","end":"2026-03-05T05:13:28.985235Z","steps":["trace[528038454] 'agreement among raft nodes before linearized reading' (duration: 178.069395ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:29.606211Z","caller":"traceutil/trace.go:171","msg":"trace[1460959256] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"132.273722ms","start":"2026-03-05T05:13:29.473897Z","end":"2026-03-05T05:13:29.606171Z","steps":["trace[1460959256] 'process raft request' (duration: 61.313687ms)","trace[1460959256] 'compare' (duration: 70.7529ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:13:31.170005Z","caller":"traceutil/trace.go:171","msg":"trace[604591732] transaction","detail":"{read_only:false; response_revision:7642; number_of_response:1; }","duration":"176.394835ms","start":"2026-03-05T05:13:30.993573Z","end":"2026-03-05T05:13:31.169968Z","steps":["trace[604591732] 'process raft request' (duration: 101.576669ms)","trace[604591732] 'compare' (duration: 74.253482ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:13:31.170168Z","caller":"traceutil/trace.go:171","msg":"trace[1117620935] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"176.084687ms","start":"2026-03-05T05:13:30.994046Z","end":"2026-03-05T05:13:31.170131Z","steps":["trace[1117620935] 'process raft request' (duration: 175.726659ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:13:31.169914Z","caller":"traceutil/trace.go:171","msg":"trace[1195967246] linearizableReadLoop","detail":"{readStateIndex:7925; appliedIndex:7923; }","duration":"167.779271ms","start":"2026-03-05T05:13:31.002109Z","end":"2026-03-05T05:13:31.169888Z","steps":["trace[1195967246] 'read index received' (duration: 93.026157ms)","trace[1195967246] 'applied index is now lower than readState.Index' (duration: 74.752084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:13:31.170487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.353934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T05:13:31.170542Z","caller":"traceutil/trace.go:171","msg":"trace[39653367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7643; }","duration":"168.473528ms","start":"2026-03-05T05:13:31.002054Z","end":"2026-03-05T05:13:31.170528Z","steps":["trace[39653367] 'agreement among raft nodes before linearized reading' (duration: 168.260103ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:14:00.217685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5813} {"level":"info","ts":"2026-03-05T05:14:00.373249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5813,"took":"153.534028ms","hash":494519495,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":22999040,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-05T05:14:00.373338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494519495,"revision":5813,"compact-revision":2800} {"level":"info","ts":"2026-03-05T05:14:09.797755Z","caller":"traceutil/trace.go:171","msg":"trace[726093439] transaction","detail":"{read_only:false; response_revision:7929; number_of_response:1; }","duration":"161.108866ms","start":"2026-03-05T05:14:09.636619Z","end":"2026-03-05T05:14:09.797727Z","steps":["trace[726093439] 'process raft request' (duration: 160.947783ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:14:10.560154Z","caller":"traceutil/trace.go:171","msg":"trace[61287837] transaction","detail":"{read_only:false; response_revision:7931; number_of_response:1; }","duration":"209.50815ms","start":"2026-03-05T05:14:10.350618Z","end":"2026-03-05T05:14:10.560126Z","steps":["trace[61287837] 'process raft request' (duration: 209.331626ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:14:10.609011Z","caller":"traceutil/trace.go:171","msg":"trace[22787570] transaction","detail":"{read_only:false; response_revision:7932; number_of_response:1; }","duration":"213.436253ms","start":"2026-03-05T05:14:10.395539Z","end":"2026-03-05T05:14:10.608975Z","steps":["trace[22787570] 'process raft request' (duration: 213.224578ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:14:10.844948Z","caller":"traceutil/trace.go:171","msg":"trace[1744609869] transaction","detail":"{read_only:false; response_revision:7933; number_of_response:1; }","duration":"230.385803ms","start":"2026-03-05T05:14:10.614519Z","end":"2026-03-05T05:14:10.844904Z","steps":["trace[1744609869] 'process raft request' (duration: 225.387635ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:15:27.98398Z","caller":"traceutil/trace.go:171","msg":"trace[155578759] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"213.483924ms","start":"2026-03-05T05:15:27.770468Z","end":"2026-03-05T05:15:27.983952Z","steps":["trace[155578759] 'process raft request' (duration: 213.33204ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:15:27.994311Z","caller":"traceutil/trace.go:171","msg":"trace[1503079995] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8958; }","duration":"164.285522ms","start":"2026-03-05T05:15:27.830001Z","end":"2026-03-05T05:15:27.994287Z","steps":["trace[1503079995] 'read index received' (duration: 153.821324ms)","trace[1503079995] 'applied index is now lower than readState.Index' (duration: 10.463318ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:15:27.994343Z","caller":"traceutil/trace.go:171","msg":"trace[48234235] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"118.950521ms","start":"2026-03-05T05:15:27.87537Z","end":"2026-03-05T05:15:27.99432Z","steps":["trace[48234235] 'process raft request' (duration: 118.842448ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:15:27.994423Z","caller":"traceutil/trace.go:171","msg":"trace[1705214371] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"220.560822ms","start":"2026-03-05T05:15:27.773836Z","end":"2026-03-05T05:15:27.994397Z","steps":["trace[1705214371] 'process raft request' (duration: 220.224174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:15:27.99479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.763343ms","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-03-05T05:15:27.99491Z","caller":"traceutil/trace.go:171","msg":"trace[748916317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8646; }","duration":"164.940217ms","start":"2026-03-05T05:15:27.829946Z","end":"2026-03-05T05:15:27.994887Z","steps":["trace[748916317] 'agreement among raft nodes before linearized reading' (duration: 164.603879ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:17:46.838337Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-05T05:17:46.845439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-03-05T05:17:46.84553Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-03-05T05:19:00.223746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7838} {"level":"info","ts":"2026-03-05T05:19:00.291184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7838,"took":"65.200569ms","hash":3876873206,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":27316224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-05T05:19:00.291258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3876873206,"revision":7838,"compact-revision":5813} {"level":"info","ts":"2026-03-05T05:24:00.230898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10321} {"level":"info","ts":"2026-03-05T05:24:00.309683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10321,"took":"76.851668ms","hash":4100210378,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":24715264,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-05T05:24:00.309753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4100210378,"revision":10321,"compact-revision":7838} {"level":"info","ts":"2026-03-05T05:24:58.504827Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032f1.wal"} {"level":"info","ts":"2026-03-05T05:25:37.387086Z","caller":"traceutil/trace.go:171","msg":"trace[2030338786] linearizableReadLoop","detail":"{readStateIndex:13257; appliedIndex:13256; }","duration":"342.097333ms","start":"2026-03-05T05:25:37.044965Z","end":"2026-03-05T05:25:37.387062Z","steps":["trace[2030338786] 'read index received' (duration: 341.97855ms)","trace[2030338786] 'applied index is now lower than readState.Index' (duration: 117.883µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T05:25:37.387117Z","caller":"traceutil/trace.go:171","msg":"trace[43819643] transaction","detail":"{read_only:false; response_revision:12794; number_of_response:1; }","duration":"351.500544ms","start":"2026-03-05T05:25:37.03559Z","end":"2026-03-05T05:25:37.38709Z","steps":["trace[43819643] 'process raft request' (duration: 351.304529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:25:37.387293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:25:37.035574Z","time spent":"351.647118ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T05:25:37.387317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.315277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-05T05:25:37.387321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.858134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-05T05:25:37.387335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.345169ms","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":"warn","ts":"2026-03-05T05:25:37.387389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.973051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-m5xsn\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-03-05T05:25:37.387404Z","caller":"traceutil/trace.go:171","msg":"trace[1250944001] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-m5xsn; range_end:; response_count:1; response_revision:12794; }","duration":"334.020432ms","start":"2026-03-05T05:25:37.05338Z","end":"2026-03-05T05:25:37.3874Z","steps":["trace[1250944001] 'agreement among raft nodes before linearized reading' (duration: 333.975961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:25:37.38743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:25:37.053363Z","time spent":"334.060093ms","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12388,"request content":"key:\"/registry/pods/openstack/openvswitch-m5xsn\" "} {"level":"info","ts":"2026-03-05T05:25:37.387421Z","caller":"traceutil/trace.go:171","msg":"trace[736099091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12794; }","duration":"342.490052ms","start":"2026-03-05T05:25:37.044911Z","end":"2026-03-05T05:25:37.387401Z","steps":["trace[736099091] 'agreement among raft nodes before linearized reading' (duration: 342.268557ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:25:37.387371Z","caller":"traceutil/trace.go:171","msg":"trace[1178141491] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:12794; }","duration":"154.922685ms","start":"2026-03-05T05:25:37.232436Z","end":"2026-03-05T05:25:37.387358Z","steps":["trace[1178141491] 'agreement among raft nodes before linearized reading' (duration: 154.832663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:25:37.387467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:25:37.044886Z","time spent":"342.571243ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T05:25:37.387344Z","caller":"traceutil/trace.go:171","msg":"trace[378767805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12794; }","duration":"339.370129ms","start":"2026-03-05T05:25:37.047968Z","end":"2026-03-05T05:25:37.387338Z","steps":["trace[378767805] 'agreement among raft nodes before linearized reading' (duration: 339.268686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T05:25:37.387589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T05:25:37.047955Z","time spent":"339.627164ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-05T05:25:46.843986Z","caller":"traceutil/trace.go:171","msg":"trace[201312164] transaction","detail":"{read_only:false; response_revision:12959; number_of_response:1; }","duration":"194.307415ms","start":"2026-03-05T05:25:46.649658Z","end":"2026-03-05T05:25:46.843966Z","steps":["trace[201312164] 'process raft request' (duration: 194.127621ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:25:46.845361Z","caller":"traceutil/trace.go:171","msg":"trace[1922777896] transaction","detail":"{read_only:false; response_revision:12960; number_of_response:1; }","duration":"194.830707ms","start":"2026-03-05T05:25:46.650519Z","end":"2026-03-05T05:25:46.845349Z","steps":["trace[1922777896] 'process raft request' (duration: 194.417517ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:29:00.236698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12206} {"level":"info","ts":"2026-03-05T05:29:00.3098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12206,"took":"71.167248ms","hash":143334592,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":27967488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-05T05:29:00.309862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":143334592,"revision":12206,"compact-revision":10321} {"level":"info","ts":"2026-03-05T05:33:10.668341Z","caller":"traceutil/trace.go:171","msg":"trace[1316261520] transaction","detail":"{read_only:false; response_revision:16330; number_of_response:1; }","duration":"232.679836ms","start":"2026-03-05T05:33:10.435638Z","end":"2026-03-05T05:33:10.668318Z","steps":["trace[1316261520] 'process raft request' (duration: 232.460491ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:33:10.668281Z","caller":"traceutil/trace.go:171","msg":"trace[928841213] linearizableReadLoop","detail":"{readStateIndex:16915; appliedIndex:16914; }","duration":"101.434311ms","start":"2026-03-05T05:33:10.566824Z","end":"2026-03-05T05:33:10.668258Z","steps":["trace[928841213] 'read index received' (duration: 101.217426ms)","trace[928841213] 'applied index is now lower than readState.Index' (duration: 216.055µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T05:33:10.668867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.010115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-sdkgw\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-03-05T05:33:10.668963Z","caller":"traceutil/trace.go:171","msg":"trace[1499634442] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-sdkgw; range_end:; response_count:1; response_revision:16330; }","duration":"102.155978ms","start":"2026-03-05T05:33:10.566786Z","end":"2026-03-05T05:33:10.668942Z","steps":["trace[1499634442] 'agreement among raft nodes before linearized reading' (duration: 101.570085ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T05:34:00.245889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14569} {"level":"info","ts":"2026-03-05T05:34:00.341674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14569,"took":"92.738497ms","hash":955247035,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":27725824,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-05T05:34:00.341759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":955247035,"revision":14569,"compact-revision":12206} {"level":"info","ts":"2026-03-05T05:39:00.251888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16658} {"level":"info","ts":"2026-03-05T05:39:00.34153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16658,"took":"87.018284ms","hash":3122094946,"current-db-size-bytes":44339200,"current-db-size":"44 MB","current-db-size-in-use-bytes":21794816,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-05T05:39:00.341594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3122094946,"revision":16658,"compact-revision":14569}