{"level":"warn","ts":"2026-04-20T08:11:03.789939Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:03.790046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.180: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.19.213.180:2380","--initial-cluster=instance=https://199.19.213.180:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.180:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.180: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-04-20T08:11:03.790127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:03.790143Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-04-20T08:11:03.790169Z","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-04-20T08:11:03.791066Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"]} {"level":"info","ts":"2026-04-20T08:11:03.791162Z","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://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.180: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-04-20T08:11:03.795122Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.685198ms"} {"level":"info","ts":"2026-04-20T08:11:03.801406Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d6864bfaf2cb565","cluster-id":"32ff95319ee9cd99"} {"level":"info","ts":"2026-04-20T08:11:03.801496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:11:03.801521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 0"} {"level":"info","ts":"2026-04-20T08:11:03.801533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d6864bfaf2cb565 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:11:03.801543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 1"} {"level":"info","ts":"2026-04-20T08:11:03.801589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"warn","ts":"2026-04-20T08:11:03.812631Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:11:03.814562Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:11:03.817658Z","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-04-20T08:11:03.820571Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d6864bfaf2cb565","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:11:03.820696Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d6864bfaf2cb565","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:11:03.821203Z","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-04-20T08:11:03.821322Z","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-04-20T08:11:03.82134Z","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-04-20T08:11:03.824749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"info","ts":"2026-04-20T08:11:03.824916Z","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-04-20T08:11:03.824953Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","added-peer-id":"5d6864bfaf2cb565","added-peer-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-04-20T08:11:03.825004Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-04-20T08:11:03.825034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-04-20T08:11:03.825188Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d6864bfaf2cb565","initial-advertise-peer-urls":["https://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:11:03.825222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:11:04.402162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:11:04.402257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:11:04.402323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgPreVoteResp from 5d6864bfaf2cb565 at term 1"} {"level":"info","ts":"2026-04-20T08:11:04.402363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:11:04.402375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgVoteResp from 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-04-20T08:11:04.402395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became leader at term 2"} {"level":"info","ts":"2026-04-20T08:11:04.402418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d6864bfaf2cb565 elected leader 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-04-20T08:11:04.403778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d6864bfaf2cb565","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.180:2379]}","request-path":"/0/members/5d6864bfaf2cb565/attributes","cluster-id":"32ff95319ee9cd99","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:11:04.403817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:04.403847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:04.404052Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:04.40426Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:11:04.404293Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:11:04.405273Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:04.405393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:04.405441Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:04.409342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.180:2379"} {"level":"info","ts":"2026-04-20T08:11:04.409455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:11:13.849553Z","caller":"traceutil/trace.go:171","msg":"trace[2131751667] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"144.453755ms","start":"2026-04-20T08:11:13.705068Z","end":"2026-04-20T08:11:13.849522Z","steps":["trace[2131751667] 'process raft request' (duration: 95.524916ms)","trace[2131751667] 'compare' (duration: 48.766735ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:11:13.868735Z","caller":"traceutil/trace.go:171","msg":"trace[1407030565] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"120.861813ms","start":"2026-04-20T08:11:13.747842Z","end":"2026-04-20T08:11:13.868704Z","steps":["trace[1407030565] 'process raft request' (duration: 120.582506ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:13.86879Z","caller":"traceutil/trace.go:171","msg":"trace[1650741010] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"118.380947ms","start":"2026-04-20T08:11:13.750377Z","end":"2026-04-20T08:11:13.868758Z","steps":["trace[1650741010] 'process raft request' (duration: 118.283494ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:14.001127Z","caller":"traceutil/trace.go:171","msg":"trace[130814021] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"125.503558ms","start":"2026-04-20T08:11:13.875585Z","end":"2026-04-20T08:11:14.001089Z","steps":["trace[130814021] 'process raft request' (duration: 78.394708ms)","trace[130814021] 'compare' (duration: 46.971647ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:11:30.527394Z","caller":"traceutil/trace.go:171","msg":"trace[1923874830] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"141.422154ms","start":"2026-04-20T08:11:30.385951Z","end":"2026-04-20T08:11:30.527373Z","steps":["trace[1923874830] 'read index received' (duration: 141.226889ms)","trace[1923874830] 'applied index is now lower than readState.Index' (duration: 194.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:11:30.527493Z","caller":"traceutil/trace.go:171","msg":"trace[783514156] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"290.466921ms","start":"2026-04-20T08:11:30.236994Z","end":"2026-04-20T08:11:30.527461Z","steps":["trace[783514156] 'process raft request' (duration: 290.265396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:11:30.527542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.569518ms","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-04-20T08:11:30.52761Z","caller":"traceutil/trace.go:171","msg":"trace[1502393241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"141.684501ms","start":"2026-04-20T08:11:30.385914Z","end":"2026-04-20T08:11:30.527598Z","steps":["trace[1502393241] 'agreement among raft nodes before linearized reading' (duration: 141.572508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:39.419133Z","caller":"traceutil/trace.go:171","msg":"trace[138244859] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"161.80522ms","start":"2026-04-20T08:11:39.257301Z","end":"2026-04-20T08:11:39.419106Z","steps":["trace[138244859] 'process raft request' (duration: 161.652606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:39.419191Z","caller":"traceutil/trace.go:171","msg":"trace[889210810] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:593; }","duration":"135.257285ms","start":"2026-04-20T08:11:39.283909Z","end":"2026-04-20T08:11:39.419166Z","steps":["trace[889210810] 'read index received' (duration: 135.250054ms)","trace[889210810] 'applied index is now lower than readState.Index' (duration: 6.211µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:11:39.419303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.376258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h58rk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T08:11:39.419348Z","caller":"traceutil/trace.go:171","msg":"trace[338100459] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h58rk; range_end:; response_count:1; response_revision:570; }","duration":"135.43739ms","start":"2026-04-20T08:11:39.283899Z","end":"2026-04-20T08:11:39.419336Z","steps":["trace[338100459] 'agreement among raft nodes before linearized reading' (duration: 135.332997ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:40.038246Z","caller":"traceutil/trace.go:171","msg":"trace[1991516570] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"516.143259ms","start":"2026-04-20T08:11:39.522045Z","end":"2026-04-20T08:11:40.038188Z","steps":["trace[1991516570] 'process raft request' (duration: 491.444353ms)","trace[1991516570] 'compare' (duration: 24.423348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:11:40.038921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:11:39.522022Z","time spent":"516.316814ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:11:40.038961Z","caller":"traceutil/trace.go:171","msg":"trace[1228153292] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"497.236479ms","start":"2026-04-20T08:11:39.541648Z","end":"2026-04-20T08:11:40.038885Z","steps":["trace[1228153292] 'process raft request' (duration: 496.425968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:40.039011Z","caller":"traceutil/trace.go:171","msg":"trace[1466507423] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"254.93057ms","start":"2026-04-20T08:11:39.784052Z","end":"2026-04-20T08:11:40.038983Z","steps":["trace[1466507423] 'process raft request' (duration: 254.751965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:11:40.039122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:11:39.541618Z","time spent":"497.425574ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:11:41.536643Z","caller":"traceutil/trace.go:171","msg":"trace[1538283813] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"108.150934ms","start":"2026-04-20T08:11:41.428465Z","end":"2026-04-20T08:11:41.536616Z","steps":["trace[1538283813] 'process raft request' (duration: 107.674171ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:29.017191Z","caller":"traceutil/trace.go:171","msg":"trace[1473112493] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"176.109996ms","start":"2026-04-20T08:12:28.841052Z","end":"2026-04-20T08:12:29.017162Z","steps":["trace[1473112493] 'read index received' (duration: 175.949732ms)","trace[1473112493] 'applied index is now lower than readState.Index' (duration: 159.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:29.017582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.503567ms","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-04-20T08:12:29.017758Z","caller":"traceutil/trace.go:171","msg":"trace[649587692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1144; }","duration":"176.750953ms","start":"2026-04-20T08:12:28.840992Z","end":"2026-04-20T08:12:29.017743Z","steps":["trace[649587692] 'agreement among raft nodes before linearized reading' (duration: 176.361462ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:29.017593Z","caller":"traceutil/trace.go:171","msg":"trace[821858471] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"181.81551ms","start":"2026-04-20T08:12:28.835729Z","end":"2026-04-20T08:12:29.017544Z","steps":["trace[821858471] 'process raft request' (duration: 181.276085ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:29.023984Z","caller":"traceutil/trace.go:171","msg":"trace[1814384146] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"144.3722ms","start":"2026-04-20T08:12:28.879402Z","end":"2026-04-20T08:12:29.023774Z","steps":["trace[1814384146] 'process raft request' (duration: 142.13353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:10.975594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.285005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:13:10.975698Z","caller":"traceutil/trace.go:171","msg":"trace[1743070136] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1859; }","duration":"210.525633ms","start":"2026-04-20T08:13:10.765159Z","end":"2026-04-20T08:13:10.975685Z","steps":["trace[1743070136] 'read index received' (duration: 51.191µs)","trace[1743070136] 'applied index is now lower than readState.Index' (duration: 210.473462ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:10.975769Z","caller":"traceutil/trace.go:171","msg":"trace[2076818104] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"415.875479ms","start":"2026-04-20T08:13:10.559883Z","end":"2026-04-20T08:13:10.975758Z","steps":["trace[2076818104] 'process raft request' (duration: 177.197893ms)","trace[2076818104] 'compare' (duration: 238.176963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:13:10.97582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:10.559864Z","time spent":"415.92518ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:13:10.976037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.887573ms","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-04-20T08:13:10.97607Z","caller":"traceutil/trace.go:171","msg":"trace[1312125453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1788; }","duration":"210.914414ms","start":"2026-04-20T08:13:10.765143Z","end":"2026-04-20T08:13:10.976058Z","steps":["trace[1312125453] 'agreement among raft nodes before linearized reading' (duration: 210.818481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:10.976234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.390116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0fc163a9-5db1-4f32-b348-36cc2095d6dc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:13:10.976358Z","caller":"traceutil/trace.go:171","msg":"trace[522967125] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0fc163a9-5db1-4f32-b348-36cc2095d6dc; range_end:; response_count:1; response_revision:1788; }","duration":"202.53991ms","start":"2026-04-20T08:13:10.773793Z","end":"2026-04-20T08:13:10.976333Z","steps":["trace[522967125] 'agreement among raft nodes before linearized reading' (duration: 202.269953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:11.125867Z","caller":"traceutil/trace.go:171","msg":"trace[748385840] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"108.987311ms","start":"2026-04-20T08:13:11.01684Z","end":"2026-04-20T08:13:11.125827Z","steps":["trace[748385840] 'read index received' (duration: 101.265075ms)","trace[748385840] 'applied index is now lower than readState.Index' (duration: 7.720896ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:11.125924Z","caller":"traceutil/trace.go:171","msg":"trace[563503785] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"143.855943ms","start":"2026-04-20T08:13:10.982031Z","end":"2026-04-20T08:13:11.125887Z","steps":["trace[563503785] 'process raft request' (duration: 136.069335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:11.126183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.328001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T08:13:11.126285Z","caller":"traceutil/trace.go:171","msg":"trace[704309013] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"109.527526ms","start":"2026-04-20T08:13:11.016732Z","end":"2026-04-20T08:13:11.126259Z","steps":["trace[704309013] 'agreement among raft nodes before linearized reading' (duration: 109.280349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:11.629928Z","caller":"traceutil/trace.go:171","msg":"trace[511083000] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"407.850165ms","start":"2026-04-20T08:13:11.222049Z","end":"2026-04-20T08:13:11.6299Z","steps":["trace[511083000] 'read index received' (duration: 407.578537ms)","trace[511083000] 'applied index is now lower than readState.Index' (duration: 270.478µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:11.629996Z","caller":"traceutil/trace.go:171","msg":"trace[1272616442] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"415.298933ms","start":"2026-04-20T08:13:11.214665Z","end":"2026-04-20T08:13:11.629964Z","steps":["trace[1272616442] 'process raft request' (duration: 415.017026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:11.630098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.030369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:13:11.630162Z","caller":"traceutil/trace.go:171","msg":"trace[285303088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1792; }","duration":"408.146622ms","start":"2026-04-20T08:13:11.222Z","end":"2026-04-20T08:13:11.630147Z","steps":["trace[285303088] 'agreement among raft nodes before linearized reading' (duration: 408.036369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:11.630157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:11.214653Z","time spent":"415.411096ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:13:11.630194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:11.22198Z","time spent":"408.208503ms","remote":"127.0.0.1:46904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:13:11.63019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.120112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:13:11.630228Z","caller":"traceutil/trace.go:171","msg":"trace[989858569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1792; }","duration":"405.243145ms","start":"2026-04-20T08:13:11.224973Z","end":"2026-04-20T08:13:11.630216Z","steps":["trace[989858569] 'agreement among raft nodes before linearized reading' (duration: 405.084241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:11.630243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.736787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-20T08:13:11.630264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:11.224954Z","time spent":"405.302076ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T08:13:11.630292Z","caller":"traceutil/trace.go:171","msg":"trace[662773548] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1792; }","duration":"165.84938ms","start":"2026-04-20T08:13:11.464427Z","end":"2026-04-20T08:13:11.630277Z","steps":["trace[662773548] 'agreement among raft nodes before linearized reading' (duration: 165.702336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:11.779826Z","caller":"traceutil/trace.go:171","msg":"trace[972544293] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"144.724636ms","start":"2026-04-20T08:13:11.635077Z","end":"2026-04-20T08:13:11.779801Z","steps":["trace[972544293] 'process raft request' (duration: 141.184841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:11.792676Z","caller":"traceutil/trace.go:171","msg":"trace[1988301779] linearizableReadLoop","detail":"{readStateIndex:1866; appliedIndex:1864; }","duration":"108.526739ms","start":"2026-04-20T08:13:11.684121Z","end":"2026-04-20T08:13:11.792648Z","steps":["trace[1988301779] 'read index received' (duration: 92.154832ms)","trace[1988301779] 'applied index is now lower than readState.Index' (duration: 16.370927ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:11.792759Z","caller":"traceutil/trace.go:171","msg":"trace[329084544] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"137.510653ms","start":"2026-04-20T08:13:11.655222Z","end":"2026-04-20T08:13:11.792733Z","steps":["trace[329084544] 'process raft request' (duration: 137.223815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:11.792823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.685213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:13:11.792927Z","caller":"traceutil/trace.go:171","msg":"trace[1874385329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1794; }","duration":"108.807286ms","start":"2026-04-20T08:13:11.684076Z","end":"2026-04-20T08:13:11.792883Z","steps":["trace[1874385329] 'agreement among raft nodes before linearized reading' (duration: 108.678183ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:55.491935Z","caller":"traceutil/trace.go:171","msg":"trace[2017504298] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"140.988115ms","start":"2026-04-20T08:13:55.350911Z","end":"2026-04-20T08:13:55.4919Z","steps":["trace[2017504298] 'process raft request' (duration: 140.747739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:56.375117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.749887ms","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-04-20T08:13:56.3752Z","caller":"traceutil/trace.go:171","msg":"trace[918077733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2090; }","duration":"393.86539ms","start":"2026-04-20T08:13:55.981316Z","end":"2026-04-20T08:13:56.375181Z","steps":["trace[918077733] 'range keys from in-memory index tree' (duration: 393.51355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:56.375243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:55.981294Z","time spent":"393.936902ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T08:13:56.375569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.692415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T08:13:56.37566Z","caller":"traceutil/trace.go:171","msg":"trace[1581840036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2090; }","duration":"393.833729ms","start":"2026-04-20T08:13:55.981806Z","end":"2026-04-20T08:13:56.37564Z","steps":["trace[1581840036] 'range keys from in-memory index tree' (duration: 393.45945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:56.375705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:55.981787Z","time spent":"393.905221ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T08:14:01.646808Z","caller":"traceutil/trace.go:171","msg":"trace[499843031] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"113.03866ms","start":"2026-04-20T08:14:01.533745Z","end":"2026-04-20T08:14:01.646783Z","steps":["trace[499843031] 'read index received' (duration: 112.942178ms)","trace[499843031] 'applied index is now lower than readState.Index' (duration: 95.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:01.647196Z","caller":"traceutil/trace.go:171","msg":"trace[1063375158] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"120.636283ms","start":"2026-04-20T08:14:01.526528Z","end":"2026-04-20T08:14:01.647165Z","steps":["trace[1063375158] 'process raft request' (duration: 120.108709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:01.647198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.41386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T08:14:01.647528Z","caller":"traceutil/trace.go:171","msg":"trace[2021277222] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"113.7405ms","start":"2026-04-20T08:14:01.533699Z","end":"2026-04-20T08:14:01.647439Z","steps":["trace[2021277222] 'agreement among raft nodes before linearized reading' (duration: 113.316808ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:06.843528Z","caller":"traceutil/trace.go:171","msg":"trace[1015081997] linearizableReadLoop","detail":"{readStateIndex:2741; appliedIndex:2740; }","duration":"185.128704ms","start":"2026-04-20T08:15:06.658375Z","end":"2026-04-20T08:15:06.843504Z","steps":["trace[1015081997] 'read index received' (duration: 184.939499ms)","trace[1015081997] 'applied index is now lower than readState.Index' (duration: 187.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:06.843594Z","caller":"traceutil/trace.go:171","msg":"trace[306928499] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"205.20258ms","start":"2026-04-20T08:15:06.638357Z","end":"2026-04-20T08:15:06.84356Z","steps":["trace[306928499] 'process raft request' (duration: 204.977854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:06.843732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.087591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:15:06.843784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.390131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T08:15:06.843771Z","caller":"traceutil/trace.go:171","msg":"trace[15074099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2638; }","duration":"159.184883ms","start":"2026-04-20T08:15:06.684576Z","end":"2026-04-20T08:15:06.843761Z","steps":["trace[15074099] 'agreement among raft nodes before linearized reading' (duration: 159.08841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:06.843878Z","caller":"traceutil/trace.go:171","msg":"trace[1602861773] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2638; }","duration":"185.525374ms","start":"2026-04-20T08:15:06.658328Z","end":"2026-04-20T08:15:06.843853Z","steps":["trace[1602861773] 'agreement among raft nodes before linearized reading' (duration: 185.315959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:06.843962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.027375ms","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-04-20T08:15:06.844062Z","caller":"traceutil/trace.go:171","msg":"trace[1619058979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2638; }","duration":"118.19157ms","start":"2026-04-20T08:15:06.725843Z","end":"2026-04-20T08:15:06.844035Z","steps":["trace[1619058979] 'agreement among raft nodes before linearized reading' (duration: 117.906583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:06.844092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.528892ms","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-04-20T08:15:06.844134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.229042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T08:15:06.844179Z","caller":"traceutil/trace.go:171","msg":"trace[1583731093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2638; }","duration":"118.298223ms","start":"2026-04-20T08:15:06.725871Z","end":"2026-04-20T08:15:06.844169Z","steps":["trace[1583731093] 'agreement among raft nodes before linearized reading' (duration: 117.920543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:06.84418Z","caller":"traceutil/trace.go:171","msg":"trace[585982959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2638; }","duration":"117.630426ms","start":"2026-04-20T08:15:06.726529Z","end":"2026-04-20T08:15:06.84416Z","steps":["trace[585982959] 'agreement among raft nodes before linearized reading' (duration: 117.297007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:06.84559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.345434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:15:06.845659Z","caller":"traceutil/trace.go:171","msg":"trace[1514948915] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2638; }","duration":"159.117711ms","start":"2026-04-20T08:15:06.686511Z","end":"2026-04-20T08:15:06.845629Z","steps":["trace[1514948915] 'agreement among raft nodes before linearized reading' (duration: 157.282182ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:40.615045Z","caller":"traceutil/trace.go:171","msg":"trace[1946444671] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"155.814913ms","start":"2026-04-20T08:15:40.459203Z","end":"2026-04-20T08:15:40.615018Z","steps":["trace[1946444671] 'process raft request' (duration: 155.397402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:18:16.064219Z","caller":"traceutil/trace.go:171","msg":"trace[1232581162] transaction","detail":"{read_only:false; response_revision:3863; number_of_response:1; }","duration":"168.016478ms","start":"2026-04-20T08:18:15.896168Z","end":"2026-04-20T08:18:16.064184Z","steps":["trace[1232581162] 'process raft request' (duration: 139.627495ms)","trace[1232581162] 'compare' (duration: 28.066884ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:18:16.064361Z","caller":"traceutil/trace.go:171","msg":"trace[1523189213] transaction","detail":"{read_only:false; response_revision:3864; number_of_response:1; }","duration":"167.808262ms","start":"2026-04-20T08:18:15.896518Z","end":"2026-04-20T08:18:16.064326Z","steps":["trace[1523189213] 'process raft request' (duration: 167.531214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:34.909402Z","caller":"traceutil/trace.go:171","msg":"trace[1509666881] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"118.221387ms","start":"2026-04-20T08:19:34.791146Z","end":"2026-04-20T08:19:34.909367Z","steps":["trace[1509666881] 'process raft request' (duration: 110.655176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:34.909781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.569336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-20T08:19:34.909855Z","caller":"traceutil/trace.go:171","msg":"trace[632230760] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4783; }","duration":"118.709831ms","start":"2026-04-20T08:19:34.791132Z","end":"2026-04-20T08:19:34.909842Z","steps":["trace[632230760] 'agreement among raft nodes before linearized reading' (duration: 118.477055ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:34.909547Z","caller":"traceutil/trace.go:171","msg":"trace[1495823370] linearizableReadLoop","detail":"{readStateIndex:4968; appliedIndex:4967; }","duration":"118.32477ms","start":"2026-04-20T08:19:34.791181Z","end":"2026-04-20T08:19:34.909506Z","steps":["trace[1495823370] 'read index received' (duration: 110.606224ms)","trace[1495823370] 'applied index is now lower than readState.Index' (duration: 7.716016ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:34.953105Z","caller":"traceutil/trace.go:171","msg":"trace[539462422] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"157.577975ms","start":"2026-04-20T08:19:34.795372Z","end":"2026-04-20T08:19:34.95295Z","steps":["trace[539462422] 'process raft request' (duration: 157.188985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:34.953583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.196504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:19:34.953645Z","caller":"traceutil/trace.go:171","msg":"trace[1308020926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4785; }","duration":"154.282727ms","start":"2026-04-20T08:19:34.799345Z","end":"2026-04-20T08:19:34.953628Z","steps":["trace[1308020926] 'agreement among raft nodes before linearized reading' (duration: 154.183605ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:43.225643Z","caller":"traceutil/trace.go:171","msg":"trace[106647339] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"126.639662ms","start":"2026-04-20T08:19:43.098967Z","end":"2026-04-20T08:19:43.225606Z","steps":["trace[106647339] 'read index received' (duration: 126.317653ms)","trace[106647339] 'applied index is now lower than readState.Index' (duration: 316.949µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:43.225812Z","caller":"traceutil/trace.go:171","msg":"trace[1006727696] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"190.223464ms","start":"2026-04-20T08:19:43.035539Z","end":"2026-04-20T08:19:43.225763Z","steps":["trace[1006727696] 'process raft request' (duration: 189.790242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:43.226034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.273139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-20T08:19:43.226034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.018322ms","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-04-20T08:19:43.226161Z","caller":"traceutil/trace.go:171","msg":"trace[750205508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5040; }","duration":"127.256948ms","start":"2026-04-20T08:19:43.098879Z","end":"2026-04-20T08:19:43.226136Z","steps":["trace[750205508] 'agreement among raft nodes before linearized reading' (duration: 126.897368ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:43.226088Z","caller":"traceutil/trace.go:171","msg":"trace[752440195] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5040; }","duration":"115.360931ms","start":"2026-04-20T08:19:43.110713Z","end":"2026-04-20T08:19:43.226074Z","steps":["trace[752440195] 'agreement among raft nodes before linearized reading' (duration: 115.134415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:43.226234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.754552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T08:19:43.226313Z","caller":"traceutil/trace.go:171","msg":"trace[793824803] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5040; }","duration":"112.842954ms","start":"2026-04-20T08:19:43.11343Z","end":"2026-04-20T08:19:43.226273Z","steps":["trace[793824803] 'agreement among raft nodes before linearized reading' (duration: 112.634189ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:43.463705Z","caller":"traceutil/trace.go:171","msg":"trace[1328952985] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5232; }","duration":"231.696929ms","start":"2026-04-20T08:19:43.231976Z","end":"2026-04-20T08:19:43.463673Z","steps":["trace[1328952985] 'read index received' (duration: 182.065277ms)","trace[1328952985] 'applied index is now lower than readState.Index' (duration: 49.630372ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:43.463749Z","caller":"traceutil/trace.go:171","msg":"trace[395925275] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"232.217802ms","start":"2026-04-20T08:19:43.23149Z","end":"2026-04-20T08:19:43.463708Z","steps":["trace[395925275] 'process raft request' (duration: 232.111129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:43.463713Z","caller":"traceutil/trace.go:171","msg":"trace[1627176750] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"232.960342ms","start":"2026-04-20T08:19:43.230707Z","end":"2026-04-20T08:19:43.463668Z","steps":["trace[1627176750] 'process raft request' (duration: 183.350291ms)","trace[1627176750] 'compare' (duration: 49.316043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:19:43.46404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.041678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16179"} {"level":"info","ts":"2026-04-20T08:19:43.464092Z","caller":"traceutil/trace.go:171","msg":"trace[1357247439] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5042; }","duration":"232.1378ms","start":"2026-04-20T08:19:43.231942Z","end":"2026-04-20T08:19:43.46408Z","steps":["trace[1357247439] 'agreement among raft nodes before linearized reading' (duration: 231.876563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:43.464405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.456827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719280"} {"level":"info","ts":"2026-04-20T08:19:43.464519Z","caller":"traceutil/trace.go:171","msg":"trace[1789569057] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5042; }","duration":"191.590781ms","start":"2026-04-20T08:19:43.272909Z","end":"2026-04-20T08:19:43.4645Z","steps":["trace[1789569057] 'agreement among raft nodes before linearized reading' (duration: 190.947504ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:56.114361Z","caller":"traceutil/trace.go:171","msg":"trace[794738033] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"136.892314ms","start":"2026-04-20T08:19:55.977436Z","end":"2026-04-20T08:19:56.114328Z","steps":["trace[794738033] 'process raft request' (duration: 136.746491ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:56.115242Z","caller":"traceutil/trace.go:171","msg":"trace[1252526811] linearizableReadLoop","detail":"{readStateIndex:5493; appliedIndex:5493; }","duration":"100.862355ms","start":"2026-04-20T08:19:56.014345Z","end":"2026-04-20T08:19:56.115208Z","steps":["trace[1252526811] 'read index received' (duration: 100.851005ms)","trace[1252526811] 'applied index is now lower than readState.Index' (duration: 8.55µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:19:56.115526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.165604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hk42w\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-20T08:19:56.115576Z","caller":"traceutil/trace.go:171","msg":"trace[417794249] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hk42w; range_end:; response_count:1; response_revision:5293; }","duration":"101.265676ms","start":"2026-04-20T08:19:56.014295Z","end":"2026-04-20T08:19:56.115561Z","steps":["trace[417794249] 'agreement among raft nodes before linearized reading' (duration: 101.01347ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:56.492501Z","caller":"traceutil/trace.go:171","msg":"trace[997173174] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"397.077181ms","start":"2026-04-20T08:19:56.095384Z","end":"2026-04-20T08:19:56.492461Z","steps":["trace[997173174] 'process raft request' (duration: 397.01005ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:56.492599Z","caller":"traceutil/trace.go:171","msg":"trace[1385443197] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"398.504669ms","start":"2026-04-20T08:19:56.094058Z","end":"2026-04-20T08:19:56.492563Z","steps":["trace[1385443197] 'process raft request' (duration: 398.1535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:56.492754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:19:56.095349Z","time spent":"397.296297ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:19:56.492789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:19:56.093991Z","time spent":"398.686554ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:19:56.497511Z","caller":"traceutil/trace.go:171","msg":"trace[1902304555] linearizableReadLoop","detail":"{readStateIndex:5496; appliedIndex:5493; }","duration":"296.351579ms","start":"2026-04-20T08:19:56.201131Z","end":"2026-04-20T08:19:56.497482Z","steps":["trace[1902304555] 'read index received' (duration: 291.048028ms)","trace[1902304555] 'applied index is now lower than readState.Index' (duration: 5.302031ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:56.497748Z","caller":"traceutil/trace.go:171","msg":"trace[192600804] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"371.931402ms","start":"2026-04-20T08:19:56.125719Z","end":"2026-04-20T08:19:56.49765Z","steps":["trace[192600804] 'process raft request' (duration: 371.589802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:56.497994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.857203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9qjn.18a802d7c885dae2\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-20T08:19:56.498031Z","caller":"traceutil/trace.go:171","msg":"trace[1545992757] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9qjn.18a802d7c885dae2; range_end:; response_count:1; response_revision:5296; }","duration":"296.934395ms","start":"2026-04-20T08:19:56.201087Z","end":"2026-04-20T08:19:56.498021Z","steps":["trace[1545992757] 'agreement among raft nodes before linearized reading' (duration: 296.752001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:56.498009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:19:56.125698Z","time spent":"372.103767ms","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:19:56.497951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.489179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:19:56.49813Z","caller":"traceutil/trace.go:171","msg":"trace[1049755573] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5296; }","duration":"164.761207ms","start":"2026-04-20T08:19:56.333339Z","end":"2026-04-20T08:19:56.4981Z","steps":["trace[1049755573] 'agreement among raft nodes before linearized reading' (duration: 164.426658ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:03.832495Z","caller":"traceutil/trace.go:171","msg":"trace[232639047] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5694; }","duration":"148.561256ms","start":"2026-04-20T08:20:03.683889Z","end":"2026-04-20T08:20:03.83245Z","steps":["trace[232639047] 'read index received' (duration: 89.305899ms)","trace[232639047] 'applied index is now lower than readState.Index' (duration: 59.253457ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:20:03.832607Z","caller":"traceutil/trace.go:171","msg":"trace[543042162] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"226.784968ms","start":"2026-04-20T08:20:03.605761Z","end":"2026-04-20T08:20:03.832545Z","steps":["trace[543042162] 'process raft request' (duration: 167.330265ms)","trace[543042162] 'compare' (duration: 59.124664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:20:03.832783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.860854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:20:03.832844Z","caller":"traceutil/trace.go:171","msg":"trace[1485919216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5490; }","duration":"149.013988ms","start":"2026-04-20T08:20:03.683813Z","end":"2026-04-20T08:20:03.832827Z","steps":["trace[1485919216] 'agreement among raft nodes before linearized reading' (duration: 148.871474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:20:03.833058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.523572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-541cc374-aa72-4cc9-ab3e-33c3ac50d8f4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T08:20:03.833187Z","caller":"traceutil/trace.go:171","msg":"trace[1972202769] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-541cc374-aa72-4cc9-ab3e-33c3ac50d8f4; range_end:; response_count:1; response_revision:5490; }","duration":"107.757679ms","start":"2026-04-20T08:20:03.725404Z","end":"2026-04-20T08:20:03.833162Z","steps":["trace[1972202769] 'agreement among raft nodes before linearized reading' (duration: 107.364349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:04.241376Z","caller":"traceutil/trace.go:171","msg":"trace[146297384] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"110.836841ms","start":"2026-04-20T08:20:04.1305Z","end":"2026-04-20T08:20:04.241337Z","steps":["trace[146297384] 'process raft request' (duration: 110.605005ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:04.434182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-20T08:21:04.585034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"149.356518ms","hash":3590058452,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":33566720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T08:21:04.585143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3590058452,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:26:04.44015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6032} {"level":"info","ts":"2026-04-20T08:26:04.545642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6032,"took":"103.781438ms","hash":3335579941,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":23584768,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-20T08:26:04.54573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335579941,"revision":6032,"compact-revision":2974} {"level":"info","ts":"2026-04-20T08:26:05.826188Z","caller":"traceutil/trace.go:171","msg":"trace[885338232] linearizableReadLoop","detail":"{readStateIndex:8556; appliedIndex:8555; }","duration":"139.185497ms","start":"2026-04-20T08:26:05.686971Z","end":"2026-04-20T08:26:05.826156Z","steps":["trace[885338232] 'read index received' (duration: 138.988851ms)","trace[885338232] 'applied index is now lower than readState.Index' (duration: 195.686µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:05.826649Z","caller":"traceutil/trace.go:171","msg":"trace[275461533] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"276.110572ms","start":"2026-04-20T08:26:05.550525Z","end":"2026-04-20T08:26:05.826635Z","steps":["trace[275461533] 'process raft request' (duration: 275.440995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:05.826882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.896575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:26:05.826954Z","caller":"traceutil/trace.go:171","msg":"trace[2038558488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8263; }","duration":"140.095711ms","start":"2026-04-20T08:26:05.686848Z","end":"2026-04-20T08:26:05.826944Z","steps":["trace[2038558488] 'agreement among raft nodes before linearized reading' (duration: 139.988618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:05.827153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.916601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130756"} {"level":"info","ts":"2026-04-20T08:26:05.827188Z","caller":"traceutil/trace.go:171","msg":"trace[843301301] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8263; }","duration":"119.978642ms","start":"2026-04-20T08:26:05.707201Z","end":"2026-04-20T08:26:05.82718Z","steps":["trace[843301301] 'agreement among raft nodes before linearized reading' (duration: 119.884259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:05.827196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.422701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-20T08:26:05.827258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.932971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7fd548d68f-7k8jp.18a8032fc89e70e2\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T08:26:05.827281Z","caller":"traceutil/trace.go:171","msg":"trace[2055466494] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8263; }","duration":"124.580855ms","start":"2026-04-20T08:26:05.702677Z","end":"2026-04-20T08:26:05.827258Z","steps":["trace[2055466494] 'agreement among raft nodes before linearized reading' (duration: 124.318188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:05.827345Z","caller":"traceutil/trace.go:171","msg":"trace[427702049] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7fd548d68f-7k8jp.18a8032fc89e70e2; range_end:; response_count:1; response_revision:8263; }","duration":"120.046054ms","start":"2026-04-20T08:26:05.707278Z","end":"2026-04-20T08:26:05.827324Z","steps":["trace[427702049] 'agreement among raft nodes before linearized reading' (duration: 119.792977ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:06.030017Z","caller":"traceutil/trace.go:171","msg":"trace[1074374080] linearizableReadLoop","detail":"{readStateIndex:8557; appliedIndex:8556; }","duration":"198.952516ms","start":"2026-04-20T08:26:05.83104Z","end":"2026-04-20T08:26:06.029993Z","steps":["trace[1074374080] 'read index received' (duration: 109.886461ms)","trace[1074374080] 'applied index is now lower than readState.Index' (duration: 89.064585ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:06.03013Z","caller":"traceutil/trace.go:171","msg":"trace[821710285] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"200.257351ms","start":"2026-04-20T08:26:05.829859Z","end":"2026-04-20T08:26:06.030117Z","steps":["trace[821710285] 'process raft request' (duration: 111.093484ms)","trace[821710285] 'compare' (duration: 88.88902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:06.030853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.777439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:53 size:76505"} {"level":"info","ts":"2026-04-20T08:26:06.031003Z","caller":"traceutil/trace.go:171","msg":"trace[565672594] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:53; response_revision:8264; }","duration":"199.974333ms","start":"2026-04-20T08:26:05.831006Z","end":"2026-04-20T08:26:06.030981Z","steps":["trace[565672594] 'agreement among raft nodes before linearized reading' (duration: 199.217383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:06.051539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.404948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:26:06.051635Z","caller":"traceutil/trace.go:171","msg":"trace[1591092642] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8266; }","duration":"100.557432ms","start":"2026-04-20T08:26:05.951055Z","end":"2026-04-20T08:26:06.051613Z","steps":["trace[1591092642] 'agreement among raft nodes before linearized reading' (duration: 100.413418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:06.052608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.476219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:53 size:76505"} {"level":"info","ts":"2026-04-20T08:26:06.052657Z","caller":"traceutil/trace.go:171","msg":"trace[1792364104] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:53; response_revision:8266; }","duration":"221.552351ms","start":"2026-04-20T08:26:05.831093Z","end":"2026-04-20T08:26:06.052646Z","steps":["trace[1792364104] 'agreement among raft nodes before linearized reading' (duration: 220.737269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:18.087025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.59933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T08:26:18.087152Z","caller":"traceutil/trace.go:171","msg":"trace[650596272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8327; }","duration":"200.752785ms","start":"2026-04-20T08:26:17.886374Z","end":"2026-04-20T08:26:18.087127Z","steps":["trace[650596272] 'range keys from in-memory index tree' (duration: 200.332983ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:18.087274Z","caller":"traceutil/trace.go:171","msg":"trace[1145299288] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"124.864803ms","start":"2026-04-20T08:26:17.962364Z","end":"2026-04-20T08:26:18.087229Z","steps":["trace[1145299288] 'process raft request' (duration: 94.48813ms)","trace[1145299288] 'compare' (duration: 29.805678ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:19.012324Z","caller":"traceutil/trace.go:171","msg":"trace[765911017] linearizableReadLoop","detail":"{readStateIndex:8632; appliedIndex:8631; }","duration":"301.80181ms","start":"2026-04-20T08:26:18.710456Z","end":"2026-04-20T08:26:19.012258Z","steps":["trace[765911017] 'read index received' (duration: 283.701386ms)","trace[765911017] 'applied index is now lower than readState.Index' (duration: 18.098464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:19.012795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.320814ms","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-04-20T08:26:19.012942Z","caller":"traceutil/trace.go:171","msg":"trace[1816488425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8335; }","duration":"302.52116ms","start":"2026-04-20T08:26:18.71039Z","end":"2026-04-20T08:26:19.012912Z","steps":["trace[1816488425] 'agreement among raft nodes before linearized reading' (duration: 302.116139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:19.013002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:18.710368Z","time spent":"302.623632ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T08:26:19.012796Z","caller":"traceutil/trace.go:171","msg":"trace[2114068675] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"354.755138ms","start":"2026-04-20T08:26:18.65776Z","end":"2026-04-20T08:26:19.012515Z","steps":["trace[2114068675] 'process raft request' (duration: 336.396376ms)","trace[2114068675] 'compare' (duration: 17.759566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:19.013339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:18.657739Z","time spent":"355.520118ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:26:19.030111Z","caller":"traceutil/trace.go:171","msg":"trace[48643284] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"124.551644ms","start":"2026-04-20T08:26:18.905539Z","end":"2026-04-20T08:26:19.03009Z","steps":["trace[48643284] 'process raft request' (duration: 124.427411ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:47.301888Z","caller":"traceutil/trace.go:171","msg":"trace[1115919356] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8842; }","duration":"156.89474ms","start":"2026-04-20T08:26:47.144964Z","end":"2026-04-20T08:26:47.301858Z","steps":["trace[1115919356] 'read index received' (duration: 156.613113ms)","trace[1115919356] 'applied index is now lower than readState.Index' (duration: 279.957µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:47.302468Z","caller":"traceutil/trace.go:171","msg":"trace[46020874] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"170.801753ms","start":"2026-04-20T08:26:47.131624Z","end":"2026-04-20T08:26:47.302426Z","steps":["trace[46020874] 'process raft request' (duration: 170.037333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.302631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.676962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T08:26:47.30268Z","caller":"traceutil/trace.go:171","msg":"trace[754377357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8539; }","duration":"157.753564ms","start":"2026-04-20T08:26:47.144914Z","end":"2026-04-20T08:26:47.302667Z","steps":["trace[754377357] 'agreement among raft nodes before linearized reading' (duration: 157.649691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.302528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.411916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T08:26:47.302827Z","caller":"traceutil/trace.go:171","msg":"trace[1539243125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8539; }","duration":"110.783286ms","start":"2026-04-20T08:26:47.192029Z","end":"2026-04-20T08:26:47.302812Z","steps":["trace[1539243125] 'agreement among raft nodes before linearized reading' (duration: 110.335794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.547468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.633587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:26:47.547687Z","caller":"traceutil/trace.go:171","msg":"trace[542212937] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8843; }","duration":"232.172266ms","start":"2026-04-20T08:26:47.315494Z","end":"2026-04-20T08:26:47.547666Z","steps":["trace[542212937] 'read index received' (duration: 76.225551ms)","trace[542212937] 'applied index is now lower than readState.Index' (duration: 155.944675ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:47.547685Z","caller":"traceutil/trace.go:171","msg":"trace[1569580425] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"240.027936ms","start":"2026-04-20T08:26:47.307631Z","end":"2026-04-20T08:26:47.547659Z","steps":["trace[1569580425] 'process raft request' (duration: 84.108042ms)","trace[1569580425] 'compare' (duration: 155.311228ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:47.54783Z","caller":"traceutil/trace.go:171","msg":"trace[599082069] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"239.554973ms","start":"2026-04-20T08:26:47.308233Z","end":"2026-04-20T08:26:47.547788Z","steps":["trace[599082069] 'process raft request' (duration: 239.363508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.547887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.383232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:26:47.547919Z","caller":"traceutil/trace.go:171","msg":"trace[2041315907] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8541; }","duration":"232.442753ms","start":"2026-04-20T08:26:47.315465Z","end":"2026-04-20T08:26:47.547908Z","steps":["trace[2041315907] 'agreement among raft nodes before linearized reading' (duration: 232.304189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.548107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.765621ms","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":"info","ts":"2026-04-20T08:26:47.548173Z","caller":"traceutil/trace.go:171","msg":"trace[1809175766] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8541; }","duration":"161.906884ms","start":"2026-04-20T08:26:47.386251Z","end":"2026-04-20T08:26:47.548158Z","steps":["trace[1809175766] 'agreement among raft nodes before linearized reading' (duration: 161.769201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:47.548366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.544795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:26:47.548407Z","caller":"traceutil/trace.go:171","msg":"trace[1938704664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8541; }","duration":"130.611217ms","start":"2026-04-20T08:26:47.417785Z","end":"2026-04-20T08:26:47.548396Z","steps":["trace[1938704664] 'agreement among raft nodes before linearized reading' (duration: 130.547115ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:00.497209Z","caller":"traceutil/trace.go:171","msg":"trace[1961547332] linearizableReadLoop","detail":"{readStateIndex:8964; appliedIndex:8960; }","duration":"158.925495ms","start":"2026-04-20T08:27:00.338251Z","end":"2026-04-20T08:27:00.497177Z","steps":["trace[1961547332] 'read index received' (duration: 91.851799ms)","trace[1961547332] 'applied index is now lower than readState.Index' (duration: 67.072116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:00.497473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.197472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T08:27:00.49752Z","caller":"traceutil/trace.go:171","msg":"trace[1316565509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8653; }","duration":"159.300525ms","start":"2026-04-20T08:27:00.338209Z","end":"2026-04-20T08:27:00.497509Z","steps":["trace[1316565509] 'agreement among raft nodes before linearized reading' (duration: 159.109959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:00.497601Z","caller":"traceutil/trace.go:171","msg":"trace[1252821687] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"338.909404ms","start":"2026-04-20T08:27:00.158639Z","end":"2026-04-20T08:27:00.497548Z","steps":["trace[1252821687] 'process raft request' (duration: 271.443167ms)","trace[1252821687] 'compare' (duration: 66.684006ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:00.49764Z","caller":"traceutil/trace.go:171","msg":"trace[1403501130] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"240.503249ms","start":"2026-04-20T08:27:00.257088Z","end":"2026-04-20T08:27:00.497591Z","steps":["trace[1403501130] 'process raft request' (duration: 239.971834ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:00.497707Z","caller":"traceutil/trace.go:171","msg":"trace[1876237269] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"183.804111ms","start":"2026-04-20T08:27:00.31386Z","end":"2026-04-20T08:27:00.497664Z","steps":["trace[1876237269] 'process raft request' (duration: 183.262646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:00.497842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:27:00.158599Z","time spent":"339.125779ms","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:27:00.49759Z","caller":"traceutil/trace.go:171","msg":"trace[1661129322] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8651; }","duration":"338.690487ms","start":"2026-04-20T08:27:00.158845Z","end":"2026-04-20T08:27:00.497535Z","steps":["trace[1661129322] 'process raft request' (duration: 338.138492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:00.499138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:27:00.158831Z","time spent":"340.038804ms","remote":"127.0.0.1:34980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-04-20T08:27:01.377882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.306709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:27:01.378024Z","caller":"traceutil/trace.go:171","msg":"trace[1330302995] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"410.919382ms","start":"2026-04-20T08:27:00.967079Z","end":"2026-04-20T08:27:01.377998Z","steps":["trace[1330302995] 'process raft request' (duration: 147.387106ms)","trace[1330302995] 'compare' (duration: 262.807746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:01.378103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:27:00.967063Z","time spent":"411.005194ms","remote":"127.0.0.1:47150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:27:02.623237Z","caller":"traceutil/trace.go:171","msg":"trace[716763237] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"117.334671ms","start":"2026-04-20T08:27:02.505845Z","end":"2026-04-20T08:27:02.62318Z","steps":["trace[716763237] 'process raft request' (duration: 116.979341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:02.73224Z","caller":"traceutil/trace.go:171","msg":"trace[1076565648] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"103.593863ms","start":"2026-04-20T08:27:02.628597Z","end":"2026-04-20T08:27:02.732191Z","steps":["trace[1076565648] 'process raft request' (duration: 70.663822ms)","trace[1076565648] 'compare' (duration: 32.344916ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:18.256179Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e9.wal"} {"level":"info","ts":"2026-04-20T08:28:07.240744Z","caller":"traceutil/trace.go:171","msg":"trace[663086271] transaction","detail":"{read_only:false; response_revision:9289; number_of_response:1; }","duration":"132.428027ms","start":"2026-04-20T08:28:07.108266Z","end":"2026-04-20T08:28:07.240694Z","steps":["trace[663086271] 'process raft request' (duration: 51.267233ms)","trace[663086271] 'compare' (duration: 80.498366ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:28:51.051939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5d6864bfaf2cb565","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T08:28:51.058442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T08:28:51.058553Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T08:31:04.446473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8258} {"level":"info","ts":"2026-04-20T08:31:04.527654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8258,"took":"79.273247ms","hash":2362084308,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":26144768,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T08:31:04.527734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2362084308,"revision":8258,"compact-revision":6032} {"level":"info","ts":"2026-04-20T08:36:04.452985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10689} {"level":"info","ts":"2026-04-20T08:36:04.536794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10689,"took":"81.620971ms","hash":144635396,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":20852736,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T08:36:04.53685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":144635396,"revision":10689,"compact-revision":8258}