{"level":"warn","ts":"2026-05-21T13:14:25.280442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T13:14:25.280533Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-05-21T13:14:25.280637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T13:14:25.280652Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-21T13:14:25.280668Z","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-05-21T13:14:25.281398Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-21T13:14:25.281667Z","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.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-05-21T13:14:25.285973Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.086238ms"} {"level":"info","ts":"2026-05-21T13:14:25.294674Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-21T13:14:25.294751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T13:14:25.294785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-21T13:14:25.29482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T13:14:25.294837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-21T13:14:25.294882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-21T13:14:25.305383Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T13:14:25.307182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T13:14:25.308251Z","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-05-21T13:14:25.310929Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T13:14:25.311099Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T13:14:25.311187Z","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-05-21T13:14:25.311349Z","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-05-21T13:14:25.31138Z","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-05-21T13:14:25.312781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-21T13:14:25.312946Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-21T13:14:25.31805Z","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-05-21T13:14:25.318188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-21T13:14:25.318258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-21T13:14:25.318492Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T13:14:25.318526Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T13:14:25.395743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T13:14:25.395868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T13:14:25.395897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-21T13:14:25.395924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-21T13:14:25.395967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-21T13:14:25.395997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-21T13:14:25.396017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-21T13:14:25.39962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T13:14:25.399751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:14:25.399837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:25.399778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:14:25.400019Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T13:14:25.400065Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T13:14:25.403524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-21T13:14:25.405756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T13:14:25.410422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:25.410482Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:25.410577Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:30.79121Z","caller":"traceutil/trace.go:171","msg":"trace[1530300059] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"121.009712ms","start":"2026-05-21T13:14:30.670173Z","end":"2026-05-21T13:14:30.791183Z","steps":["trace[1530300059] 'process raft request' (duration: 120.807966ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:30.792714Z","caller":"traceutil/trace.go:171","msg":"trace[1956172021] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"115.74724ms","start":"2026-05-21T13:14:30.676929Z","end":"2026-05-21T13:14:30.792677Z","steps":["trace[1956172021] 'process raft request' (duration: 115.552874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:37.366796Z","caller":"traceutil/trace.go:171","msg":"trace[1658001834] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"110.55114ms","start":"2026-05-21T13:14:37.256205Z","end":"2026-05-21T13:14:37.366756Z","steps":["trace[1658001834] 'read index received' (duration: 110.291692ms)","trace[1658001834] 'applied index is now lower than readState.Index' (duration: 257.698µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:14:37.366933Z","caller":"traceutil/trace.go:171","msg":"trace[508753956] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"145.787417ms","start":"2026-05-21T13:14:37.221112Z","end":"2026-05-21T13:14:37.3669Z","steps":["trace[508753956] 'process raft request' (duration: 145.391736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:14:37.367168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.871069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T13:14:37.36732Z","caller":"traceutil/trace.go:171","msg":"trace[74147914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"111.132977ms","start":"2026-05-21T13:14:37.256135Z","end":"2026-05-21T13:14:37.367268Z","steps":["trace[74147914] 'agreement among raft nodes before linearized reading' (duration: 110.781316ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:37.499894Z","caller":"traceutil/trace.go:171","msg":"trace[389733777] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"127.356194ms","start":"2026-05-21T13:14:37.372474Z","end":"2026-05-21T13:14:37.49983Z","steps":["trace[389733777] 'process raft request' (duration: 107.063689ms)","trace[389733777] 'compare' (duration: 20.171362ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:14:37.530973Z","caller":"traceutil/trace.go:171","msg":"trace[1139652075] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"156.890486ms","start":"2026-05-21T13:14:37.374029Z","end":"2026-05-21T13:14:37.530919Z","steps":["trace[1139652075] 'process raft request' (duration: 156.763212ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:37.689Z","caller":"traceutil/trace.go:171","msg":"trace[1758565762] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"191.489795ms","start":"2026-05-21T13:14:37.497478Z","end":"2026-05-21T13:14:37.688967Z","steps":["trace[1758565762] 'process raft request' (duration: 179.314414ms)","trace[1758565762] 'compare' (duration: 12.029317ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:15:03.768347Z","caller":"traceutil/trace.go:171","msg":"trace[1714124611] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"119.111532ms","start":"2026-05-21T13:15:03.6492Z","end":"2026-05-21T13:15:03.768312Z","steps":["trace[1714124611] 'process raft request' (duration: 82.688146ms)","trace[1714124611] 'compare' (duration: 36.268152ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:15:04.389996Z","caller":"traceutil/trace.go:171","msg":"trace[942190721] transaction","detail":"{read_only:false; number_of_response:0; response_revision:511; }","duration":"103.288301ms","start":"2026-05-21T13:15:04.286681Z","end":"2026-05-21T13:15:04.389969Z","steps":["trace[942190721] 'process raft request' (duration: 102.923111ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:04.390127Z","caller":"traceutil/trace.go:171","msg":"trace[722212353] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"100.41963ms","start":"2026-05-21T13:15:04.289679Z","end":"2026-05-21T13:15:04.390099Z","steps":["trace[722212353] 'process raft request' (duration: 100.200954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:04.390154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.420054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-05-21T13:15:04.390218Z","caller":"traceutil/trace.go:171","msg":"trace[1230145050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:512; }","duration":"103.537297ms","start":"2026-05-21T13:15:04.286663Z","end":"2026-05-21T13:15:04.3902Z","steps":["trace[1230145050] 'agreement among raft nodes before linearized reading' (duration: 103.352602ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:04.397211Z","caller":"traceutil/trace.go:171","msg":"trace[1763048825] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"102.221ms","start":"2026-05-21T13:15:04.294959Z","end":"2026-05-21T13:15:04.39718Z","steps":["trace[1763048825] 'process raft request' (duration: 102.035135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:06.93424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.938202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:15:06.934374Z","caller":"traceutil/trace.go:171","msg":"trace[281287223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"150.131457ms","start":"2026-05-21T13:15:06.784218Z","end":"2026-05-21T13:15:06.934349Z","steps":["trace[281287223] 'range keys from in-memory index tree' (duration: 149.83663ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:10.746161Z","caller":"traceutil/trace.go:171","msg":"trace[729905128] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"121.494819ms","start":"2026-05-21T13:15:10.624611Z","end":"2026-05-21T13:15:10.746106Z","steps":["trace[729905128] 'process raft request' (duration: 108.899767ms)","trace[729905128] 'compare' (duration: 12.258022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:15:10.747458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.535443ms","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-05-21T13:15:10.747533Z","caller":"traceutil/trace.go:171","msg":"trace[1630067723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"100.698828ms","start":"2026-05-21T13:15:10.64682Z","end":"2026-05-21T13:15:10.747519Z","steps":["trace[1630067723] 'agreement among raft nodes before linearized reading' (duration: 99.182536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:11.28444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.773229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vhpck\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-21T13:15:11.284561Z","caller":"traceutil/trace.go:171","msg":"trace[1264483289] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vhpck; range_end:; response_count:1; response_revision:599; }","duration":"380.964304ms","start":"2026-05-21T13:15:10.903571Z","end":"2026-05-21T13:15:11.284535Z","steps":["trace[1264483289] 'range keys from in-memory index tree' (duration: 380.602024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:11.284624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:15:10.903546Z","time spent":"381.064478ms","remote":"127.0.0.1:58472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-vhpck\" "} {"level":"info","ts":"2026-05-21T13:15:11.304534Z","caller":"traceutil/trace.go:171","msg":"trace[419234402] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"104.307929ms","start":"2026-05-21T13:15:11.20019Z","end":"2026-05-21T13:15:11.304498Z","steps":["trace[419234402] 'process raft request' (duration: 103.466226ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:44.11797Z","caller":"traceutil/trace.go:171","msg":"trace[704032879] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"235.005309ms","start":"2026-05-21T13:16:43.882939Z","end":"2026-05-21T13:16:44.117944Z","steps":["trace[704032879] 'process raft request' (duration: 234.829244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:44.262656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.727772ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T13:16:44.262858Z","caller":"traceutil/trace.go:171","msg":"trace[13956414] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1918; }","duration":"112.417339ms","start":"2026-05-21T13:16:44.150425Z","end":"2026-05-21T13:16:44.262843Z","steps":["trace[13956414] 'read index received' (duration: 11.51665ms)","trace[13956414] 'applied index is now lower than readState.Index' (duration: 100.899969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:16:44.263212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.776279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T13:16:44.263214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.641952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T13:16:44.263153Z","caller":"traceutil/trace.go:171","msg":"trace[1256892127] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"133.830522ms","start":"2026-05-21T13:16:44.129192Z","end":"2026-05-21T13:16:44.263023Z","steps":["trace[1256892127] 'process raft request' (duration: 133.549355ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:44.263281Z","caller":"traceutil/trace.go:171","msg":"trace[420731354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1846; }","duration":"109.728895ms","start":"2026-05-21T13:16:44.153525Z","end":"2026-05-21T13:16:44.263254Z","steps":["trace[420731354] 'agreement among raft nodes before linearized reading' (duration: 109.57ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:44.263249Z","caller":"traceutil/trace.go:171","msg":"trace[1954687828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1846; }","duration":"112.873911ms","start":"2026-05-21T13:16:44.150367Z","end":"2026-05-21T13:16:44.263241Z","steps":["trace[1954687828] 'agreement among raft nodes before linearized reading' (duration: 112.639985ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:44.601301Z","caller":"traceutil/trace.go:171","msg":"trace[1614823837] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"177.563376ms","start":"2026-05-21T13:16:44.423712Z","end":"2026-05-21T13:16:44.601275Z","steps":["trace[1614823837] 'process raft request' (duration: 177.35298ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:23.703605Z","caller":"traceutil/trace.go:171","msg":"trace[1868368889] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"198.034558ms","start":"2026-05-21T13:17:23.505537Z","end":"2026-05-21T13:17:23.703571Z","steps":["trace[1868368889] 'process raft request' (duration: 197.802772ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:34.939838Z","caller":"traceutil/trace.go:171","msg":"trace[1415234902] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"154.821193ms","start":"2026-05-21T13:17:34.784974Z","end":"2026-05-21T13:17:34.939795Z","steps":["trace[1415234902] 'read index received' (duration: 132.600614ms)","trace[1415234902] 'applied index is now lower than readState.Index' (duration: 22.219029ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:17:34.939881Z","caller":"traceutil/trace.go:171","msg":"trace[1596668181] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"177.413792ms","start":"2026-05-21T13:17:34.762414Z","end":"2026-05-21T13:17:34.939828Z","steps":["trace[1596668181] 'process raft request' (duration: 155.147652ms)","trace[1596668181] 'compare' (duration: 21.571602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:17:34.940166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.170543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:17:34.940298Z","caller":"traceutil/trace.go:171","msg":"trace[1069496573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2174; }","duration":"155.381488ms","start":"2026-05-21T13:17:34.784896Z","end":"2026-05-21T13:17:34.940277Z","steps":["trace[1069496573] 'agreement among raft nodes before linearized reading' (duration: 155.104811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:17:34.940221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.398914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:17:34.940416Z","caller":"traceutil/trace.go:171","msg":"trace[503476941] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2174; }","duration":"114.682472ms","start":"2026-05-21T13:17:34.825716Z","end":"2026-05-21T13:17:34.940398Z","steps":["trace[503476941] 'agreement among raft nodes before linearized reading' (duration: 114.418995ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:35.540701Z","caller":"traceutil/trace.go:171","msg":"trace[1416288817] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2259; }","duration":"146.52174ms","start":"2026-05-21T13:17:35.394149Z","end":"2026-05-21T13:17:35.540671Z","steps":["trace[1416288817] 'read index received' (duration: 146.268673ms)","trace[1416288817] 'applied index is now lower than readState.Index' (duration: 251.697µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:17:35.540822Z","caller":"traceutil/trace.go:171","msg":"trace[1762355115] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"191.824351ms","start":"2026-05-21T13:17:35.348954Z","end":"2026-05-21T13:17:35.540778Z","steps":["trace[1762355115] 'process raft request' (duration: 191.470401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:17:35.540908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.745995ms","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-05-21T13:17:35.540942Z","caller":"traceutil/trace.go:171","msg":"trace[136871300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2175; }","duration":"146.840618ms","start":"2026-05-21T13:17:35.394091Z","end":"2026-05-21T13:17:35.540931Z","steps":["trace[136871300] 'agreement among raft nodes before linearized reading' (duration: 146.687374ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:40.847212Z","caller":"traceutil/trace.go:171","msg":"trace[952635898] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"116.450369ms","start":"2026-05-21T13:17:40.730728Z","end":"2026-05-21T13:17:40.847178Z","steps":["trace[952635898] 'process raft request' (duration: 116.259604ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:40.847203Z","caller":"traceutil/trace.go:171","msg":"trace[797856921] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"116.737336ms","start":"2026-05-21T13:17:40.730414Z","end":"2026-05-21T13:17:40.847152Z","steps":["trace[797856921] 'process raft request' (duration: 116.355886ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:19:00.023674Z","caller":"traceutil/trace.go:171","msg":"trace[753328511] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"143.827417ms","start":"2026-05-21T13:18:59.879823Z","end":"2026-05-21T13:19:00.02365Z","steps":["trace[753328511] 'process raft request' (duration: 143.449077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:19:00.228104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.656362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:19:00.228246Z","caller":"traceutil/trace.go:171","msg":"trace[322084478] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"199.794352ms","start":"2026-05-21T13:19:00.028427Z","end":"2026-05-21T13:19:00.228222Z","steps":["trace[322084478] 'process raft request' (duration: 87.485082ms)","trace[322084478] 'compare' (duration: 111.496958ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:19:00.228664Z","caller":"traceutil/trace.go:171","msg":"trace[800969723] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"106.325599ms","start":"2026-05-21T13:19:00.122297Z","end":"2026-05-21T13:19:00.228622Z","steps":["trace[800969723] 'process raft request' (duration: 106.174305ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:20:26.09655Z","caller":"traceutil/trace.go:171","msg":"trace[835887715] linearizableReadLoop","detail":"{readStateIndex:3413; appliedIndex:3412; }","duration":"445.859449ms","start":"2026-05-21T13:20:25.650662Z","end":"2026-05-21T13:20:26.096521Z","steps":["trace[835887715] 'read index received' (duration: 445.572041ms)","trace[835887715] 'applied index is now lower than readState.Index' (duration: 286.238µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:20:26.09664Z","caller":"traceutil/trace.go:171","msg":"trace[1088637281] transaction","detail":"{read_only:false; response_revision:3285; number_of_response:1; }","duration":"496.494866ms","start":"2026-05-21T13:20:25.600104Z","end":"2026-05-21T13:20:26.096599Z","steps":["trace[1088637281] 'process raft request' (duration: 496.215068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.096815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.600079Z","time spent":"496.647809ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:20:26.096828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.208497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:20:26.096903Z","caller":"traceutil/trace.go:171","msg":"trace[1694566064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3285; }","duration":"310.333251ms","start":"2026-05-21T13:20:25.786552Z","end":"2026-05-21T13:20:26.096885Z","steps":["trace[1694566064] 'agreement among raft nodes before linearized reading' (duration: 310.187127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.096884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.471258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T13:20:26.096946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.786531Z","time spent":"310.405532ms","remote":"127.0.0.1:58280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T13:20:26.09697Z","caller":"traceutil/trace.go:171","msg":"trace[1627512267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3285; }","duration":"445.567741ms","start":"2026-05-21T13:20:25.65138Z","end":"2026-05-21T13:20:26.096948Z","steps":["trace[1627512267] 'agreement among raft nodes before linearized reading' (duration: 445.331774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.096918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.152127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:20:26.097022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.651357Z","time spent":"445.655623ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T13:20:26.097111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.113956ms","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-05-21T13:20:26.097162Z","caller":"traceutil/trace.go:171","msg":"trace[152680688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3285; }","duration":"446.515416ms","start":"2026-05-21T13:20:25.650635Z","end":"2026-05-21T13:20:26.09715Z","steps":["trace[152680688] 'agreement among raft nodes before linearized reading' (duration: 446.041613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.097156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.576803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:20:26.097199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.650622Z","time spent":"446.567148ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T13:20:26.09725Z","caller":"traceutil/trace.go:171","msg":"trace[26052136] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:3285; }","duration":"208.730457ms","start":"2026-05-21T13:20:25.888498Z","end":"2026-05-21T13:20:26.097228Z","steps":["trace[26052136] 'agreement among raft nodes before linearized reading' (duration: 208.507211ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:20:26.097544Z","caller":"traceutil/trace.go:171","msg":"trace[1737623177] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3285; }","duration":"384.309141ms","start":"2026-05-21T13:20:25.712734Z","end":"2026-05-21T13:20:26.097043Z","steps":["trace[1737623177] 'agreement among raft nodes before linearized reading' (duration: 384.111446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.097656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.71269Z","time spent":"384.944979ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-21T13:20:28.462097Z","caller":"traceutil/trace.go:171","msg":"trace[1818625105] transaction","detail":"{read_only:false; response_revision:3298; number_of_response:1; }","duration":"115.413564ms","start":"2026-05-21T13:20:28.346657Z","end":"2026-05-21T13:20:28.462071Z","steps":["trace[1818625105] 'process raft request' (duration: 115.25438ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:20:28.891141Z","caller":"traceutil/trace.go:171","msg":"trace[1512135466] transaction","detail":"{read_only:false; response_revision:3299; number_of_response:1; }","duration":"278.38062ms","start":"2026-05-21T13:20:28.612736Z","end":"2026-05-21T13:20:28.891116Z","steps":["trace[1512135466] 'process raft request' (duration: 278.089491ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:20:28.89115Z","caller":"traceutil/trace.go:171","msg":"trace[26338747] linearizableReadLoop","detail":"{readStateIndex:3427; appliedIndex:3427; }","duration":"261.589536ms","start":"2026-05-21T13:20:28.629527Z","end":"2026-05-21T13:20:28.891117Z","steps":["trace[26338747] 'read index received' (duration: 261.576925ms)","trace[26338747] 'applied index is now lower than readState.Index' (duration: 10.41µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:20:28.891388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.835743ms","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-05-21T13:20:28.891457Z","caller":"traceutil/trace.go:171","msg":"trace[1463060762] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3299; }","duration":"261.963976ms","start":"2026-05-21T13:20:28.629478Z","end":"2026-05-21T13:20:28.891442Z","steps":["trace[1463060762] 'agreement among raft nodes before linearized reading' (duration: 261.73606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:28.891646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.100633ms","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-05-21T13:20:28.891716Z","caller":"traceutil/trace.go:171","msg":"trace[1051267778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3299; }","duration":"240.188506ms","start":"2026-05-21T13:20:28.651511Z","end":"2026-05-21T13:20:28.891699Z","steps":["trace[1051267778] 'agreement among raft nodes before linearized reading' (duration: 239.9829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:28.891689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.139374ms","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-05-21T13:20:28.891771Z","caller":"traceutil/trace.go:171","msg":"trace[163266098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3299; }","duration":"240.242587ms","start":"2026-05-21T13:20:28.651508Z","end":"2026-05-21T13:20:28.89175Z","steps":["trace[163266098] 'agreement among raft nodes before linearized reading' (duration: 240.058612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:28.892465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.012695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:20:28.892556Z","caller":"traceutil/trace.go:171","msg":"trace[424476310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3299; }","duration":"106.159019ms","start":"2026-05-21T13:20:28.786374Z","end":"2026-05-21T13:20:28.892533Z","steps":["trace[424476310] 'agreement among raft nodes before linearized reading' (duration: 105.182932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:34.699627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.674114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:20:34.699796Z","caller":"traceutil/trace.go:171","msg":"trace[1333648107] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"216.265635ms","start":"2026-05-21T13:20:34.483498Z","end":"2026-05-21T13:20:34.699764Z","steps":["trace[1333648107] 'compare' (duration: 212.775249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:00.002648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.271077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-21T13:23:00.002748Z","caller":"traceutil/trace.go:171","msg":"trace[1779790589] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4908; }","duration":"103.418501ms","start":"2026-05-21T13:22:59.899309Z","end":"2026-05-21T13:23:00.002727Z","steps":["trace[1779790589] 'range keys from in-memory index tree' (duration: 103.059151ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:04.932639Z","caller":"traceutil/trace.go:171","msg":"trace[367067280] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5079; }","duration":"137.277219ms","start":"2026-05-21T13:23:04.79533Z","end":"2026-05-21T13:23:04.932607Z","steps":["trace[367067280] 'process raft request' (duration: 137.151526ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:18.632769Z","caller":"traceutil/trace.go:171","msg":"trace[179420964] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"105.522018ms","start":"2026-05-21T13:23:18.52721Z","end":"2026-05-21T13:23:18.632732Z","steps":["trace[179420964] 'process raft request' (duration: 105.288902ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:31.222018Z","caller":"traceutil/trace.go:171","msg":"trace[854121861] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5728; }","duration":"223.821132ms","start":"2026-05-21T13:23:30.998152Z","end":"2026-05-21T13:23:31.221973Z","steps":["trace[854121861] 'read index received' (duration: 223.656667ms)","trace[854121861] 'applied index is now lower than readState.Index' (duration: 163.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:31.222193Z","caller":"traceutil/trace.go:171","msg":"trace[714377221] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"283.20741ms","start":"2026-05-21T13:23:30.938974Z","end":"2026-05-21T13:23:31.222181Z","steps":["trace[714377221] 'process raft request' (duration: 282.767917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:31.222675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.707479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T13:23:31.222797Z","caller":"traceutil/trace.go:171","msg":"trace[310018036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5521; }","duration":"129.875645ms","start":"2026-05-21T13:23:31.092893Z","end":"2026-05-21T13:23:31.222769Z","steps":["trace[310018036] 'agreement among raft nodes before linearized reading' (duration: 129.599387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:31.222797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.611333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-21T13:23:31.223041Z","caller":"traceutil/trace.go:171","msg":"trace[388637530] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5521; }","duration":"224.956293ms","start":"2026-05-21T13:23:30.998073Z","end":"2026-05-21T13:23:31.223029Z","steps":["trace[388637530] 'agreement among raft nodes before linearized reading' (duration: 224.282514ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:24:25.774276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-05-21T13:24:25.912185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"136.583937ms","hash":3789740252,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":33677312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-21T13:24:25.912244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3789740252,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-05-21T13:29:25.78091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6039} {"level":"info","ts":"2026-05-21T13:29:25.880981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6039,"took":"98.157757ms","hash":1719702998,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":21893120,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-21T13:29:25.88105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1719702998,"revision":6039,"compact-revision":2969} {"level":"info","ts":"2026-05-21T13:29:40.760099Z","caller":"traceutil/trace.go:171","msg":"trace[1442998617] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"148.244989ms","start":"2026-05-21T13:29:40.611817Z","end":"2026-05-21T13:29:40.760062Z","steps":["trace[1442998617] 'process raft request' (duration: 148.036383ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:29:44.890495Z","caller":"traceutil/trace.go:171","msg":"trace[815858498] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"119.503052ms","start":"2026-05-21T13:29:44.770968Z","end":"2026-05-21T13:29:44.890471Z","steps":["trace[815858498] 'process raft request' (duration: 119.300497ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:29:54.11478Z","caller":"traceutil/trace.go:171","msg":"trace[1636587695] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"106.66561ms","start":"2026-05-21T13:29:54.008076Z","end":"2026-05-21T13:29:54.114742Z","steps":["trace[1636587695] 'process raft request' (duration: 59.626382ms)","trace[1636587695] 'compare' (duration: 46.896724ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:30:34.594652Z","caller":"traceutil/trace.go:171","msg":"trace[790541414] linearizableReadLoop","detail":"{readStateIndex:9064; appliedIndex:9063; }","duration":"154.126143ms","start":"2026-05-21T13:30:34.440497Z","end":"2026-05-21T13:30:34.594623Z","steps":["trace[790541414] 'read index received' (duration: 154.082852ms)","trace[790541414] 'applied index is now lower than readState.Index' (duration: 41.841µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:30:34.594772Z","caller":"traceutil/trace.go:171","msg":"trace[661906430] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"162.147027ms","start":"2026-05-21T13:30:34.432614Z","end":"2026-05-21T13:30:34.594761Z","steps":["trace[661906430] 'process raft request' (duration: 161.847919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:30:34.594969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.444511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:30:34.59502Z","caller":"traceutil/trace.go:171","msg":"trace[1083626928] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8751; }","duration":"154.532024ms","start":"2026-05-21T13:30:34.440468Z","end":"2026-05-21T13:30:34.595Z","steps":["trace[1083626928] 'agreement among raft nodes before linearized reading' (duration: 154.438171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:30:34.621166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.117256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:30:34.62126Z","caller":"traceutil/trace.go:171","msg":"trace[486721019] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8752; }","duration":"156.26034ms","start":"2026-05-21T13:30:34.464976Z","end":"2026-05-21T13:30:34.621236Z","steps":["trace[486721019] 'agreement among raft nodes before linearized reading' (duration: 156.058625ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:34.621176Z","caller":"traceutil/trace.go:171","msg":"trace[1370504784] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"161.336405ms","start":"2026-05-21T13:30:34.459805Z","end":"2026-05-21T13:30:34.621142Z","steps":["trace[1370504784] 'process raft request' (duration: 161.064428ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:49.521268Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f0.wal"} {"level":"info","ts":"2026-05-21T13:31:52.061225Z","caller":"traceutil/trace.go:171","msg":"trace[638170965] transaction","detail":"{read_only:false; response_revision:9508; number_of_response:1; }","duration":"249.253783ms","start":"2026-05-21T13:31:51.811933Z","end":"2026-05-21T13:31:52.061187Z","steps":["trace[638170965] 'process raft request' (duration: 237.184479ms)","trace[638170965] 'compare' (duration: 11.824398ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:31:52.066359Z","caller":"traceutil/trace.go:171","msg":"trace[1398846039] linearizableReadLoop","detail":"{readStateIndex:9840; appliedIndex:9838; }","duration":"232.009921ms","start":"2026-05-21T13:31:51.834336Z","end":"2026-05-21T13:31:52.066346Z","steps":["trace[1398846039] 'read index received' (duration: 214.883311ms)","trace[1398846039] 'applied index is now lower than readState.Index' (duration: 17.114869ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:31:52.066439Z","caller":"traceutil/trace.go:171","msg":"trace[1443259086] transaction","detail":"{read_only:false; response_revision:9509; number_of_response:1; }","duration":"254.139063ms","start":"2026-05-21T13:31:51.812279Z","end":"2026-05-21T13:31:52.066418Z","steps":["trace[1443259086] 'process raft request' (duration: 253.963899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:31:52.066662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.309988ms","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-05-21T13:31:52.066752Z","caller":"traceutil/trace.go:171","msg":"trace[1120848393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9509; }","duration":"232.436822ms","start":"2026-05-21T13:31:51.834298Z","end":"2026-05-21T13:31:52.066735Z","steps":["trace[1120848393] 'agreement among raft nodes before linearized reading' (duration: 232.148174ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:32:11.62326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T13:32:11.630247Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-21T13:32:11.630338Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-21T13:34:25.790412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8150} {"level":"info","ts":"2026-05-21T13:34:25.866064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8150,"took":"72.886596ms","hash":2022045071,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":29327360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-21T13:34:25.86612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2022045071,"revision":8150,"compact-revision":6039} {"level":"info","ts":"2026-05-21T13:35:18.35705Z","caller":"traceutil/trace.go:171","msg":"trace[1063530780] transaction","detail":"{read_only:false; response_revision:11259; number_of_response:1; }","duration":"101.436846ms","start":"2026-05-21T13:35:18.255593Z","end":"2026-05-21T13:35:18.357029Z","steps":["trace[1063530780] 'process raft request' (duration: 101.083637ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:39:25.798342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10678} {"level":"info","ts":"2026-05-21T13:39:25.87988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10678,"took":"79.74206ms","hash":3286723818,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":27648000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-21T13:39:25.879942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3286723818,"revision":10678,"compact-revision":8150} {"level":"info","ts":"2026-05-21T13:41:35.152669Z","caller":"traceutil/trace.go:171","msg":"trace[827849673] transaction","detail":"{read_only:false; response_revision:13792; number_of_response:1; }","duration":"101.420362ms","start":"2026-05-21T13:41:35.051217Z","end":"2026-05-21T13:41:35.152638Z","steps":["trace[827849673] 'process raft request' (duration: 101.168515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:41:37.903837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.012783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:41:37.903965Z","caller":"traceutil/trace.go:171","msg":"trace[1804627179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13809; }","duration":"116.148757ms","start":"2026-05-21T13:41:37.787788Z","end":"2026-05-21T13:41:37.903937Z","steps":["trace[1804627179] 'range keys from in-memory index tree' (duration: 115.678735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:41:57.685396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.264602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:41:57.685503Z","caller":"traceutil/trace.go:171","msg":"trace[1957960510] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:13912; }","duration":"185.438786ms","start":"2026-05-21T13:41:57.500044Z","end":"2026-05-21T13:41:57.685483Z","steps":["trace[1957960510] 'count revisions from in-memory index tree' (duration: 185.102848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:41:57.685562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.711999ms","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-05-21T13:41:57.685647Z","caller":"traceutil/trace.go:171","msg":"trace[966903458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13912; }","duration":"139.841563ms","start":"2026-05-21T13:41:57.545787Z","end":"2026-05-21T13:41:57.685629Z","steps":["trace[966903458] 'range keys from in-memory index tree' (duration: 139.548305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:41:57.685695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.477025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:41:57.685818Z","caller":"traceutil/trace.go:171","msg":"trace[1459400212] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:13912; }","duration":"205.66118ms","start":"2026-05-21T13:41:57.480134Z","end":"2026-05-21T13:41:57.685795Z","steps":["trace[1459400212] 'count revisions from in-memory index tree' (duration: 205.106664ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:41:57.686887Z","caller":"traceutil/trace.go:171","msg":"trace[912566617] transaction","detail":"{read_only:false; response_revision:13913; number_of_response:1; }","duration":"122.522538ms","start":"2026-05-21T13:41:57.564337Z","end":"2026-05-21T13:41:57.68686Z","steps":["trace[912566617] 'process raft request' (duration: 122.261761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:14.148384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.119521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:42:14.148542Z","caller":"traceutil/trace.go:171","msg":"trace[501335907] transaction","detail":"{read_only:false; response_revision:14011; number_of_response:1; }","duration":"373.513584ms","start":"2026-05-21T13:42:13.775001Z","end":"2026-05-21T13:42:14.148514Z","steps":["trace[501335907] 'process raft request' (duration: 227.164207ms)","trace[501335907] 'compare' (duration: 145.977637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:42:14.148987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.774973Z","time spent":"373.952486ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:42:14.165483Z","caller":"traceutil/trace.go:171","msg":"trace[412231643] transaction","detail":"{read_only:false; response_revision:14012; number_of_response:1; }","duration":"385.998669ms","start":"2026-05-21T13:42:13.779452Z","end":"2026-05-21T13:42:14.16545Z","steps":["trace[412231643] 'process raft request' (duration: 385.579868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:14.165646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.779413Z","time spent":"386.149013ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:42:14.165765Z","caller":"traceutil/trace.go:171","msg":"trace[1822958265] transaction","detail":"{read_only:false; response_revision:14013; number_of_response:1; }","duration":"358.05039ms","start":"2026-05-21T13:42:13.807689Z","end":"2026-05-21T13:42:14.165739Z","steps":["trace[1822958265] 'process raft request' (duration: 357.714491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:14.165911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.807671Z","time spent":"358.165773ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:42:14.166237Z","caller":"traceutil/trace.go:171","msg":"trace[1009967830] transaction","detail":"{read_only:false; response_revision:14014; number_of_response:1; }","duration":"356.58625ms","start":"2026-05-21T13:42:13.809636Z","end":"2026-05-21T13:42:14.166222Z","steps":["trace[1009967830] 'process raft request' (duration: 356.019905ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:14.166306Z","caller":"traceutil/trace.go:171","msg":"trace[1918518035] linearizableReadLoop","detail":"{readStateIndex:14493; appliedIndex:14491; }","duration":"378.92463ms","start":"2026-05-21T13:42:13.787368Z","end":"2026-05-21T13:42:14.166293Z","steps":["trace[1918518035] 'read index received' (duration: 214.816445ms)","trace[1918518035] 'applied index is now lower than readState.Index' (duration: 164.106635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:42:14.166316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.809611Z","time spent":"356.666072ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:42:14.166472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.104654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:42:14.166457Z","caller":"traceutil/trace.go:171","msg":"trace[963037233] transaction","detail":"{read_only:false; response_revision:14015; number_of_response:1; }","duration":"132.811524ms","start":"2026-05-21T13:42:14.033607Z","end":"2026-05-21T13:42:14.166419Z","steps":["trace[963037233] 'process raft request' (duration: 132.611859ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:14.166513Z","caller":"traceutil/trace.go:171","msg":"trace[1361267265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14015; }","duration":"379.187476ms","start":"2026-05-21T13:42:13.787316Z","end":"2026-05-21T13:42:14.166504Z","steps":["trace[1361267265] 'agreement among raft nodes before linearized reading' (duration: 379.092284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:14.166546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.787297Z","time spent":"379.239748ms","remote":"127.0.0.1:58280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:42:14.166704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.35182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-21T13:42:14.166795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.05327ms","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-05-21T13:42:14.166811Z","caller":"traceutil/trace.go:171","msg":"trace[879146076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14015; }","duration":"370.505324ms","start":"2026-05-21T13:42:13.796278Z","end":"2026-05-21T13:42:14.166783Z","steps":["trace[879146076] 'agreement among raft nodes before linearized reading' (duration: 370.261217ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:14.166856Z","caller":"traceutil/trace.go:171","msg":"trace[2128925675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14015; }","duration":"361.192883ms","start":"2026-05-21T13:42:13.805647Z","end":"2026-05-21T13:42:14.16684Z","steps":["trace[2128925675] 'agreement among raft nodes before linearized reading' (duration: 361.014349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:14.166864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.796257Z","time spent":"370.597546ms","remote":"127.0.0.1:58550","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":"warn","ts":"2026-05-21T13:42:14.166897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:42:13.805633Z","time spent":"361.253776ms","remote":"127.0.0.1:58550","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-05-21T13:42:35.758696Z","caller":"traceutil/trace.go:171","msg":"trace[1982141234] linearizableReadLoop","detail":"{readStateIndex:14816; appliedIndex:14815; }","duration":"171.921884ms","start":"2026-05-21T13:42:35.586757Z","end":"2026-05-21T13:42:35.758679Z","steps":["trace[1982141234] 'read index received' (duration: 171.77418ms)","trace[1982141234] 'applied index is now lower than readState.Index' (duration: 147.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:42:35.759039Z","caller":"traceutil/trace.go:171","msg":"trace[2139322786] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"258.209129ms","start":"2026-05-21T13:42:35.500814Z","end":"2026-05-21T13:42:35.759024Z","steps":["trace[2139322786] 'process raft request' (duration: 257.761067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:35.759306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.534301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-21T13:42:35.759349Z","caller":"traceutil/trace.go:171","msg":"trace[1437352125] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14321; }","duration":"172.618103ms","start":"2026-05-21T13:42:35.58672Z","end":"2026-05-21T13:42:35.759338Z","steps":["trace[1437352125] 'agreement among raft nodes before linearized reading' (duration: 172.481479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:35.75954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.484815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:42:35.759581Z","caller":"traceutil/trace.go:171","msg":"trace[1381014037] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:14321; }","duration":"148.546887ms","start":"2026-05-21T13:42:35.611022Z","end":"2026-05-21T13:42:35.759569Z","steps":["trace[1381014037] 'agreement among raft nodes before linearized reading' (duration: 148.483365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:35.760058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.487977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T13:42:35.7601Z","caller":"traceutil/trace.go:171","msg":"trace[563208617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14321; }","duration":"119.543688ms","start":"2026-05-21T13:42:35.640545Z","end":"2026-05-21T13:42:35.760089Z","steps":["trace[563208617] 'agreement among raft nodes before linearized reading' (duration: 119.455946ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:28.548726Z","caller":"traceutil/trace.go:171","msg":"trace[1434065460] transaction","detail":"{read_only:false; response_revision:14955; number_of_response:1; }","duration":"137.630592ms","start":"2026-05-21T13:43:28.41107Z","end":"2026-05-21T13:43:28.5487Z","steps":["trace[1434065460] 'process raft request' (duration: 137.471298ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:28.560547Z","caller":"traceutil/trace.go:171","msg":"trace[684611917] linearizableReadLoop","detail":"{readStateIndex:15468; appliedIndex:15464; }","duration":"128.4726ms","start":"2026-05-21T13:43:28.432048Z","end":"2026-05-21T13:43:28.560521Z","steps":["trace[684611917] 'read index received' (duration: 116.479899ms)","trace[684611917] 'applied index is now lower than readState.Index' (duration: 11.991671ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:43:28.560598Z","caller":"traceutil/trace.go:171","msg":"trace[684007475] transaction","detail":"{read_only:false; response_revision:14957; number_of_response:1; }","duration":"134.620389ms","start":"2026-05-21T13:43:28.425953Z","end":"2026-05-21T13:43:28.560573Z","steps":["trace[684007475] 'process raft request' (duration: 134.492146ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:28.56065Z","caller":"traceutil/trace.go:171","msg":"trace[129142372] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"143.237326ms","start":"2026-05-21T13:43:28.417396Z","end":"2026-05-21T13:43:28.560633Z","steps":["trace[129142372] 'process raft request' (duration: 142.916467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:28.56081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.780708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:43:28.560872Z","caller":"traceutil/trace.go:171","msg":"trace[1634453207] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14958; }","duration":"128.886941ms","start":"2026-05-21T13:43:28.431968Z","end":"2026-05-21T13:43:28.560855Z","steps":["trace[1634453207] 'agreement among raft nodes before linearized reading' (duration: 128.779878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:28.560916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.929734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-21T13:43:28.561008Z","caller":"traceutil/trace.go:171","msg":"trace[111125257] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14958; }","duration":"128.077719ms","start":"2026-05-21T13:43:28.432911Z","end":"2026-05-21T13:43:28.560989Z","steps":["trace[111125257] 'agreement among raft nodes before linearized reading' (duration: 127.805521ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:28.561023Z","caller":"traceutil/trace.go:171","msg":"trace[666191911] transaction","detail":"{read_only:false; response_revision:14958; number_of_response:1; }","duration":"132.338526ms","start":"2026-05-21T13:43:28.428333Z","end":"2026-05-21T13:43:28.560672Z","steps":["trace[666191911] 'process raft request' (duration: 132.149501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:28.56149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.041398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-21T13:43:28.562156Z","caller":"traceutil/trace.go:171","msg":"trace[1262626185] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14958; }","duration":"124.734737ms","start":"2026-05-21T13:43:28.437396Z","end":"2026-05-21T13:43:28.562131Z","steps":["trace[1262626185] 'agreement among raft nodes before linearized reading' (duration: 123.38566ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:44:25.803997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12845} {"level":"info","ts":"2026-05-21T13:44:25.884144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12845,"took":"77.833784ms","hash":4287664451,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":29900800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T13:44:25.884209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4287664451,"revision":12845,"compact-revision":10678} {"level":"info","ts":"2026-05-21T13:49:25.810641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15254} {"level":"info","ts":"2026-05-21T13:49:25.908398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15254,"took":"93.592424ms","hash":4147778566,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T13:49:25.908478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4147778566,"revision":15254,"compact-revision":12845} {"level":"info","ts":"2026-05-21T13:54:25.817095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17554} {"level":"info","ts":"2026-05-21T13:54:25.907572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17554,"took":"87.421822ms","hash":2914624896,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":26726400,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-21T13:54:25.907615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2914624896,"revision":17554,"compact-revision":15254} {"level":"info","ts":"2026-05-21T13:55:04.232694Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T13:55:04.238196Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-21T13:55:04.238313Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}