{"level":"warn","ts":"2026-09-15T22:45:21.104541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:45:21.104666Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.53:2380","--initial-cluster=instance=https://162.253.55.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-15T22:45:21.104781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:45:21.104803Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.53:2380"]} {"level":"info","ts":"2026-09-15T22:45:21.104839Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-15T22:45:21.105939Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.53:2379"]} {"level":"info","ts":"2026-09-15T22:45:21.106026Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.53:2380"],"listen-peer-urls":["https://162.253.55.53:2380"],"advertise-client-urls":["https://162.253.55.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-15T22:45:21.10971Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.432658ms"} {"level":"info","ts":"2026-09-15T22:45:21.117966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6ec433552cd258e","cluster-id":"97f38a787f2a5ce2"} {"level":"info","ts":"2026-09-15T22:45:21.118085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:45:21.118127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e became follower at term 0"} {"level":"info","ts":"2026-09-15T22:45:21.11815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6ec433552cd258e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:45:21.118163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e became follower at term 1"} {"level":"info","ts":"2026-09-15T22:45:21.118221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e switched to configuration voters=(12028062601101256078)"} {"level":"warn","ts":"2026-09-15T22:45:21.124481Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:45:21.130443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:45:21.132541Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-15T22:45:21.146905Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6ec433552cd258e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:45:21.147079Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6ec433552cd258e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:45:21.147198Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:21.147337Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:21.147353Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:21.148233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e switched to configuration voters=(12028062601101256078)"} {"level":"info","ts":"2026-09-15T22:45:21.148364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97f38a787f2a5ce2","local-member-id":"a6ec433552cd258e","added-peer-id":"a6ec433552cd258e","added-peer-peer-urls":["https://162.253.55.53:2380"]} {"level":"info","ts":"2026-09-15T22:45:21.15133Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-15T22:45:21.151482Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.53:2380"} {"level":"info","ts":"2026-09-15T22:45:21.151576Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.53:2380"} {"level":"info","ts":"2026-09-15T22:45:21.151808Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6ec433552cd258e","initial-advertise-peer-urls":["https://162.253.55.53:2380"],"listen-peer-urls":["https://162.253.55.53:2380"],"advertise-client-urls":["https://162.253.55.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:45:21.151855Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:45:22.119431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:45:22.119489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:45:22.119529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e received MsgPreVoteResp from a6ec433552cd258e at term 1"} {"level":"info","ts":"2026-09-15T22:45:22.119597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:45:22.119617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e received MsgVoteResp from a6ec433552cd258e at term 2"} {"level":"info","ts":"2026-09-15T22:45:22.119633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6ec433552cd258e became leader at term 2"} {"level":"info","ts":"2026-09-15T22:45:22.119684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6ec433552cd258e elected leader a6ec433552cd258e at term 2"} {"level":"info","ts":"2026-09-15T22:45:22.120998Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6ec433552cd258e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.53:2379]}","request-path":"/0/members/a6ec433552cd258e/attributes","cluster-id":"97f38a787f2a5ce2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:45:22.121057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:45:22.121125Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:22.121084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:45:22.121308Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:45:22.121365Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:45:22.12204Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97f38a787f2a5ce2","local-member-id":"a6ec433552cd258e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:22.122133Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:22.122168Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:22.124638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.53:2379"} {"level":"info","ts":"2026-09-15T22:45:22.125048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:45:25.211701Z","caller":"traceutil/trace.go:171","msg":"trace[675969086] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"100.818546ms","start":"2026-09-15T22:45:25.110857Z","end":"2026-09-15T22:45:25.211675Z","steps":["trace[675969086] 'process raft request' (duration: 95.198092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:10.083507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.544089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:46:10.08374Z","caller":"traceutil/trace.go:171","msg":"trace[1577442825] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"292.480915ms","start":"2026-09-15T22:46:09.791221Z","end":"2026-09-15T22:46:10.083702Z","steps":["trace[1577442825] 'process raft request' (duration: 165.663404ms)","trace[1577442825] 'compare' (duration: 124.984884ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:10.083716Z","caller":"traceutil/trace.go:171","msg":"trace[895719902] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"138.814317ms","start":"2026-09-15T22:46:09.944884Z","end":"2026-09-15T22:46:10.083698Z","steps":["trace[895719902] 'read index received' (duration: 11.794391ms)","trace[895719902] 'applied index is now lower than readState.Index' (duration: 127.018376ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:10.083971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.071424ms","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-09-15T22:46:10.084062Z","caller":"traceutil/trace.go:171","msg":"trace[1565575527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"139.227609ms","start":"2026-09-15T22:46:09.944811Z","end":"2026-09-15T22:46:10.084038Z","steps":["trace[1565575527] 'agreement among raft nodes before linearized reading' (duration: 139.016893ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:10.226219Z","caller":"traceutil/trace.go:171","msg":"trace[47746410] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"136.5671ms","start":"2026-09-15T22:46:10.089586Z","end":"2026-09-15T22:46:10.226153Z","steps":["trace[47746410] 'process raft request' (duration: 136.316304ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:10.815362Z","caller":"traceutil/trace.go:171","msg":"trace[919878124] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"182.113104ms","start":"2026-09-15T22:46:10.63318Z","end":"2026-09-15T22:46:10.815293Z","steps":["trace[919878124] 'process raft request' (duration: 181.402626ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:15.842517Z","caller":"traceutil/trace.go:171","msg":"trace[540160865] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"112.25757ms","start":"2026-09-15T22:46:15.730191Z","end":"2026-09-15T22:46:15.842449Z","steps":["trace[540160865] 'process raft request' (duration: 111.716285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:27.291447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.203245ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:46:27.291632Z","caller":"traceutil/trace.go:171","msg":"trace[25013002] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:768; }","duration":"297.40153ms","start":"2026-09-15T22:46:26.994211Z","end":"2026-09-15T22:46:27.291613Z","steps":["trace[25013002] 'range keys from in-memory index tree' (duration: 297.186145ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:56.745163Z","caller":"traceutil/trace.go:171","msg":"trace[1694743359] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"217.300329ms","start":"2026-09-15T22:46:56.527836Z","end":"2026-09-15T22:46:56.745136Z","steps":["trace[1694743359] 'read index received' (duration: 198.057446ms)","trace[1694743359] 'applied index is now lower than readState.Index' (duration: 19.241503ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:56.745217Z","caller":"traceutil/trace.go:171","msg":"trace[673823573] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"281.348831ms","start":"2026-09-15T22:46:56.463844Z","end":"2026-09-15T22:46:56.745193Z","steps":["trace[673823573] 'process raft request' (duration: 262.106847ms)","trace[673823573] 'compare' (duration: 19.005198ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:56.745399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.097471ms","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-09-15T22:46:56.745428Z","caller":"traceutil/trace.go:171","msg":"trace[1533481106] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1168; }","duration":"197.167103ms","start":"2026-09-15T22:46:56.548252Z","end":"2026-09-15T22:46:56.745419Z","steps":["trace[1533481106] 'agreement among raft nodes before linearized reading' (duration: 197.088631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:56.745476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.620377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T22:46:56.74557Z","caller":"traceutil/trace.go:171","msg":"trace[1335168249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"217.77689ms","start":"2026-09-15T22:46:56.527776Z","end":"2026-09-15T22:46:56.745553Z","steps":["trace[1335168249] 'agreement among raft nodes before linearized reading' (duration: 217.519494ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:47:47.19038Z","caller":"traceutil/trace.go:171","msg":"trace[450714372] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"186.840108ms","start":"2026-09-15T22:47:47.003513Z","end":"2026-09-15T22:47:47.190353Z","steps":["trace[450714372] 'read index received' (duration: 186.793187ms)","trace[450714372] 'applied index is now lower than readState.Index' (duration: 44.951µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:47:47.190499Z","caller":"traceutil/trace.go:171","msg":"trace[998398860] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"188.645645ms","start":"2026-09-15T22:47:47.001804Z","end":"2026-09-15T22:47:47.190449Z","steps":["trace[998398860] 'process raft request' (duration: 188.384618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:47:47.190755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.202968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T22:47:47.190839Z","caller":"traceutil/trace.go:171","msg":"trace[452148049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1855; }","duration":"187.378972ms","start":"2026-09-15T22:47:47.003441Z","end":"2026-09-15T22:47:47.19082Z","steps":["trace[452148049] 'agreement among raft nodes before linearized reading' (duration: 187.058434ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:47:47.261831Z","caller":"traceutil/trace.go:171","msg":"trace[886418603] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"112.489323ms","start":"2026-09-15T22:47:47.149281Z","end":"2026-09-15T22:47:47.26177Z","steps":["trace[886418603] 'process raft request' (duration: 100.560667ms)","trace[886418603] 'compare' (duration: 11.640209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:47:47.261977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.117621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-733570ef-5760-44a3-80fa-2c437390318b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-15T22:47:47.262057Z","caller":"traceutil/trace.go:171","msg":"trace[848204582] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-733570ef-5760-44a3-80fa-2c437390318b; range_end:; response_count:1; response_revision:1857; }","duration":"243.216913ms","start":"2026-09-15T22:47:47.01882Z","end":"2026-09-15T22:47:47.262037Z","steps":["trace[848204582] 'agreement among raft nodes before linearized reading' (duration: 242.985877ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:04.140992Z","caller":"traceutil/trace.go:171","msg":"trace[1276435122] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"144.526234ms","start":"2026-09-15T22:48:03.996444Z","end":"2026-09-15T22:48:04.14097Z","steps":["trace[1276435122] 'process raft request' (duration: 144.403961ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:04.185341Z","caller":"traceutil/trace.go:171","msg":"trace[763591694] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"179.436408ms","start":"2026-09-15T22:48:04.005882Z","end":"2026-09-15T22:48:04.185318Z","steps":["trace[763591694] 'process raft request' (duration: 179.248974ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:04.185486Z","caller":"traceutil/trace.go:171","msg":"trace[1611603809] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"164.370373ms","start":"2026-09-15T22:48:04.021083Z","end":"2026-09-15T22:48:04.185453Z","steps":["trace[1611603809] 'read index received' (duration: 119.878763ms)","trace[1611603809] 'applied index is now lower than readState.Index' (duration: 44.48916ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:04.185771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.664651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:48:04.185819Z","caller":"traceutil/trace.go:171","msg":"trace[47554843] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1997; }","duration":"164.771793ms","start":"2026-09-15T22:48:04.021037Z","end":"2026-09-15T22:48:04.185809Z","steps":["trace[47554843] 'agreement among raft nodes before linearized reading' (duration: 164.595519ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:42.909322Z","caller":"traceutil/trace.go:171","msg":"trace[1430484349] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"476.303716ms","start":"2026-09-15T22:48:42.432998Z","end":"2026-09-15T22:48:42.909302Z","steps":["trace[1430484349] 'read index received' (duration: 476.160302ms)","trace[1430484349] 'applied index is now lower than readState.Index' (duration: 142.374µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:42.909438Z","caller":"traceutil/trace.go:171","msg":"trace[1923958582] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"552.896798ms","start":"2026-09-15T22:48:42.35653Z","end":"2026-09-15T22:48:42.909427Z","steps":["trace[1923958582] 'process raft request' (duration: 552.58613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:42.909535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.356507Z","time spent":"552.95356ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:48:42.909694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.692485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T22:48:42.909789Z","caller":"traceutil/trace.go:171","msg":"trace[439498786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2205; }","duration":"476.836268ms","start":"2026-09-15T22:48:42.432936Z","end":"2026-09-15T22:48:42.909772Z","steps":["trace[439498786] 'agreement among raft nodes before linearized reading' (duration: 476.597753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:42.90983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.432912Z","time spent":"476.908771ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-15T22:48:43.209766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.589229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:48:43.209998Z","caller":"traceutil/trace.go:171","msg":"trace[660028922] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"296.489018ms","start":"2026-09-15T22:48:42.913488Z","end":"2026-09-15T22:48:43.209977Z","steps":["trace[660028922] 'process raft request' (duration: 296.407156ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:43.210145Z","caller":"traceutil/trace.go:171","msg":"trace[1637415935] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"465.813188ms","start":"2026-09-15T22:48:42.744299Z","end":"2026-09-15T22:48:43.210112Z","steps":["trace[1637415935] 'process raft request' (duration: 364.778879ms)","trace[1637415935] 'compare' (duration: 100.435383ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:43.210167Z","caller":"traceutil/trace.go:171","msg":"trace[966677610] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"300.744467ms","start":"2026-09-15T22:48:42.909396Z","end":"2026-09-15T22:48:43.210141Z","steps":["trace[966677610] 'read index received' (duration: 199.671067ms)","trace[966677610] 'applied index is now lower than readState.Index' (duration: 101.07129ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:43.210265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.744272Z","time spent":"465.93778ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:48:43.210401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.666199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-15T22:48:43.210465Z","caller":"traceutil/trace.go:171","msg":"trace[1102589431] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2207; }","duration":"322.771411ms","start":"2026-09-15T22:48:42.887675Z","end":"2026-09-15T22:48:43.210446Z","steps":["trace[1102589431] 'agreement among raft nodes before linearized reading' (duration: 322.513685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.210462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.462385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-15T22:48:43.210541Z","caller":"traceutil/trace.go:171","msg":"trace[176715823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2207; }","duration":"445.594139ms","start":"2026-09-15T22:48:42.764927Z","end":"2026-09-15T22:48:43.210521Z","steps":["trace[176715823] 'agreement among raft nodes before linearized reading' (duration: 445.353193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.210551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.188131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-15T22:48:43.21059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.764905Z","time spent":"445.677312ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-15T22:48:43.210574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.887664Z","time spent":"322.836373ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-15T22:48:43.210596Z","caller":"traceutil/trace.go:171","msg":"trace[1459087802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2207; }","duration":"106.237103ms","start":"2026-09-15T22:48:43.104349Z","end":"2026-09-15T22:48:43.210586Z","steps":["trace[1459087802] 'agreement among raft nodes before linearized reading' (duration: 106.058878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.542506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.962752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:48:43.542714Z","caller":"traceutil/trace.go:171","msg":"trace[1795227501] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2293; }","duration":"320.433691ms","start":"2026-09-15T22:48:43.222263Z","end":"2026-09-15T22:48:43.542696Z","steps":["trace[1795227501] 'read index received' (duration: 163.09281ms)","trace[1795227501] 'applied index is now lower than readState.Index' (duration: 157.339721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:43.542827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.564744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:48:43.542862Z","caller":"traceutil/trace.go:171","msg":"trace[322542711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2209; }","duration":"320.636666ms","start":"2026-09-15T22:48:43.222212Z","end":"2026-09-15T22:48:43.542849Z","steps":["trace[322542711] 'agreement among raft nodes before linearized reading' (duration: 320.579255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.542897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.222194Z","time spent":"320.692398ms","remote":"127.0.0.1:41056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-09-15T22:48:43.542884Z","caller":"traceutil/trace.go:171","msg":"trace[1746025637] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"327.712498ms","start":"2026-09-15T22:48:43.215108Z","end":"2026-09-15T22:48:43.542821Z","steps":["trace[1746025637] 'process raft request' (duration: 327.531674ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:43.542921Z","caller":"traceutil/trace.go:171","msg":"trace[1069865745] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"328.055487ms","start":"2026-09-15T22:48:43.214821Z","end":"2026-09-15T22:48:43.542877Z","steps":["trace[1069865745] 'process raft request' (duration: 170.630353ms)","trace[1069865745] 'compare' (duration: 156.833649ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:43.543077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.747794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:48:43.543123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.215083Z","time spent":"327.924004ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:48:43.543152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.214801Z","time spent":"328.251352ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:48:43.543161Z","caller":"traceutil/trace.go:171","msg":"trace[352460612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"118.886917ms","start":"2026-09-15T22:48:43.424251Z","end":"2026-09-15T22:48:43.543138Z","steps":["trace[352460612] 'agreement among raft nodes before linearized reading' (duration: 118.699183ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:49:21.443442Z","caller":"traceutil/trace.go:171","msg":"trace[1664799252] linearizableReadLoop","detail":"{readStateIndex:2692; appliedIndex:2691; }","duration":"146.251409ms","start":"2026-09-15T22:49:21.297159Z","end":"2026-09-15T22:49:21.44341Z","steps":["trace[1664799252] 'read index received' (duration: 146.167767ms)","trace[1664799252] 'applied index is now lower than readState.Index' (duration: 82.262µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:49:21.443604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.435243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:49:21.443575Z","caller":"traceutil/trace.go:171","msg":"trace[280420082] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"238.181335ms","start":"2026-09-15T22:49:21.205361Z","end":"2026-09-15T22:49:21.443542Z","steps":["trace[280420082] 'process raft request' (duration: 237.852577ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:49:21.443664Z","caller":"traceutil/trace.go:171","msg":"trace[549840578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2592; }","duration":"146.553126ms","start":"2026-09-15T22:49:21.297099Z","end":"2026-09-15T22:49:21.443652Z","steps":["trace[549840578] 'agreement among raft nodes before linearized reading' (duration: 146.444464ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:49:21.471085Z","caller":"traceutil/trace.go:171","msg":"trace[55364359] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"126.180845ms","start":"2026-09-15T22:49:21.344887Z","end":"2026-09-15T22:49:21.471068Z","steps":["trace[55364359] 'process raft request' (duration: 126.099403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:49:56.905741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.335522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:49:56.905877Z","caller":"traceutil/trace.go:171","msg":"trace[566056390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2806; }","duration":"132.543298ms","start":"2026-09-15T22:49:56.773307Z","end":"2026-09-15T22:49:56.90585Z","steps":["trace[566056390] 'count revisions from in-memory index tree' (duration: 132.179808ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:53:04.056422Z","caller":"traceutil/trace.go:171","msg":"trace[974093356] transaction","detail":"{read_only:false; response_revision:3991; number_of_response:1; }","duration":"105.960469ms","start":"2026-09-15T22:53:03.950438Z","end":"2026-09-15T22:53:04.056398Z","steps":["trace[974093356] 'process raft request' (duration: 105.848436ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:22.719542Z","caller":"traceutil/trace.go:171","msg":"trace[533057570] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"110.121174ms","start":"2026-09-15T22:54:22.609395Z","end":"2026-09-15T22:54:22.719516Z","steps":["trace[533057570] 'process raft request' (duration: 109.94551ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:29.457563Z","caller":"traceutil/trace.go:171","msg":"trace[50957017] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"102.651127ms","start":"2026-09-15T22:54:29.354878Z","end":"2026-09-15T22:54:29.457529Z","steps":["trace[50957017] 'process raft request' (duration: 102.427542ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:29.457856Z","caller":"traceutil/trace.go:171","msg":"trace[692500490] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"102.640766ms","start":"2026-09-15T22:54:29.355167Z","end":"2026-09-15T22:54:29.457808Z","steps":["trace[692500490] 'process raft request' (duration: 102.313088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:54:32.972241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.233729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:54:32.972404Z","caller":"traceutil/trace.go:171","msg":"trace[2090850204] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"249.328247ms","start":"2026-09-15T22:54:32.723047Z","end":"2026-09-15T22:54:32.972375Z","steps":["trace[2090850204] 'process raft request' (duration: 88.860073ms)","trace[2090850204] 'compare' (duration: 160.112215ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:54:33.002408Z","caller":"traceutil/trace.go:171","msg":"trace[990747245] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5373; }","duration":"233.998852ms","start":"2026-09-15T22:54:32.768385Z","end":"2026-09-15T22:54:33.002384Z","steps":["trace[990747245] 'read index received' (duration: 43.519157ms)","trace[990747245] 'applied index is now lower than readState.Index' (duration: 190.478475ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:54:33.00246Z","caller":"traceutil/trace.go:171","msg":"trace[2014875598] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"248.514316ms","start":"2026-09-15T22:54:32.753913Z","end":"2026-09-15T22:54:33.002427Z","steps":["trace[2014875598] 'process raft request' (duration: 248.327592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:54:33.002582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.161137ms","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-09-15T22:54:33.002675Z","caller":"traceutil/trace.go:171","msg":"trace[1336119589] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5178; }","duration":"234.300961ms","start":"2026-09-15T22:54:32.768355Z","end":"2026-09-15T22:54:33.002656Z","steps":["trace[1336119589] 'agreement among raft nodes before linearized reading' (duration: 234.124976ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:48.09186Z","caller":"traceutil/trace.go:171","msg":"trace[136716000] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"129.662873ms","start":"2026-09-15T22:54:47.962141Z","end":"2026-09-15T22:54:48.091803Z","steps":["trace[136716000] 'process raft request' (duration: 67.505768ms)","trace[136716000] 'compare' (duration: 61.847958ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:54:48.091864Z","caller":"traceutil/trace.go:171","msg":"trace[1359590698] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"109.1384ms","start":"2026-09-15T22:54:47.982675Z","end":"2026-09-15T22:54:48.091813Z","steps":["trace[1359590698] 'process raft request' (duration: 109.033938ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:48.739341Z","caller":"traceutil/trace.go:171","msg":"trace[378800875] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"351.182756ms","start":"2026-09-15T22:54:48.388124Z","end":"2026-09-15T22:54:48.739306Z","steps":["trace[378800875] 'process raft request' (duration: 351.017511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:54:48.739495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:54:48.388095Z","time spent":"351.324549ms","remote":"127.0.0.1:57886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:54:48.765942Z","caller":"traceutil/trace.go:171","msg":"trace[741341738] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"291.800639ms","start":"2026-09-15T22:54:48.47412Z","end":"2026-09-15T22:54:48.76592Z","steps":["trace[741341738] 'process raft request' (duration: 291.649326ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:49.272395Z","caller":"traceutil/trace.go:171","msg":"trace[665682911] linearizableReadLoop","detail":"{readStateIndex:5668; appliedIndex:5667; }","duration":"122.122445ms","start":"2026-09-15T22:54:49.150253Z","end":"2026-09-15T22:54:49.272376Z","steps":["trace[665682911] 'read index received' (duration: 122.025011ms)","trace[665682911] 'applied index is now lower than readState.Index' (duration: 96.584µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:54:49.272466Z","caller":"traceutil/trace.go:171","msg":"trace[1998855405] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"132.273429ms","start":"2026-09-15T22:54:49.140153Z","end":"2026-09-15T22:54:49.272427Z","steps":["trace[1998855405] 'process raft request' (duration: 132.084794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:54:49.27258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.314279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T22:54:49.272613Z","caller":"traceutil/trace.go:171","msg":"trace[1058493326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5461; }","duration":"122.389711ms","start":"2026-09-15T22:54:49.150211Z","end":"2026-09-15T22:54:49.272601Z","steps":["trace[1058493326] 'agreement among raft nodes before linearized reading' (duration: 122.264788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:54:56.692596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.895175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad52a8c5-5329-4921-b51e-45c2c22b17c0\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-15T22:54:56.692686Z","caller":"traceutil/trace.go:171","msg":"trace[818929024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad52a8c5-5329-4921-b51e-45c2c22b17c0; range_end:; response_count:1; response_revision:5642; }","duration":"107.001957ms","start":"2026-09-15T22:54:56.585664Z","end":"2026-09-15T22:54:56.692666Z","steps":["trace[818929024] 'range keys from in-memory index tree' (duration: 106.723329ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:54:57.221039Z","caller":"traceutil/trace.go:171","msg":"trace[446783526] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"119.719406ms","start":"2026-09-15T22:54:57.101284Z","end":"2026-09-15T22:54:57.221003Z","steps":["trace[446783526] 'process raft request' (duration: 119.5198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:07.839738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.123365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-15T22:55:07.839855Z","caller":"traceutil/trace.go:171","msg":"trace[1874527104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5766; }","duration":"187.236248ms","start":"2026-09-15T22:55:07.652577Z","end":"2026-09-15T22:55:07.839813Z","steps":["trace[1874527104] 'range keys from in-memory index tree' (duration: 186.927458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:07.839819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.130247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhfmg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-15T22:55:07.83993Z","caller":"traceutil/trace.go:171","msg":"trace[332627615] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhfmg; range_end:; response_count:1; response_revision:5766; }","duration":"159.25254ms","start":"2026-09-15T22:55:07.680657Z","end":"2026-09-15T22:55:07.839909Z","steps":["trace[332627615] 'range keys from in-memory index tree' (duration: 158.991195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:07.839943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.15941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-15T22:55:07.839944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.816761ms","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-09-15T22:55:07.839996Z","caller":"traceutil/trace.go:171","msg":"trace[54727304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5766; }","duration":"234.242732ms","start":"2026-09-15T22:55:07.605738Z","end":"2026-09-15T22:55:07.839981Z","steps":["trace[54727304] 'range keys from in-memory index tree' (duration: 234.042127ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:07.840049Z","caller":"traceutil/trace.go:171","msg":"trace[164917930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5766; }","duration":"176.973034ms","start":"2026-09-15T22:55:07.663053Z","end":"2026-09-15T22:55:07.840026Z","steps":["trace[164917930] 'range keys from in-memory index tree' (duration: 176.598535ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:07.840108Z","caller":"traceutil/trace.go:171","msg":"trace[948281605] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"121.751809ms","start":"2026-09-15T22:55:07.718342Z","end":"2026-09-15T22:55:07.840093Z","steps":["trace[948281605] 'process raft request' (duration: 102.071285ms)","trace[948281605] 'compare' (duration: 19.16012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:55:07.840215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.093095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-15T22:55:07.840391Z","caller":"traceutil/trace.go:171","msg":"trace[745867635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5766; }","duration":"164.30222ms","start":"2026-09-15T22:55:07.676082Z","end":"2026-09-15T22:55:07.840384Z","steps":["trace[745867635] 'range keys from in-memory index tree' (duration: 163.830059ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:22.154923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2946} {"level":"info","ts":"2026-09-15T22:55:22.243436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2946,"took":"86.529017ms","hash":3403246793,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":32526336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-15T22:55:22.243512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3403246793,"revision":2946,"compact-revision":-1} {"level":"info","ts":"2026-09-15T23:00:22.195702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5876} {"level":"info","ts":"2026-09-15T23:00:22.301467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5876,"took":"94.525113ms","hash":1081067429,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":26296320,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-15T23:00:22.301583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081067429,"revision":5876,"compact-revision":2946} {"level":"info","ts":"2026-09-15T23:01:17.216936Z","caller":"traceutil/trace.go:171","msg":"trace[1218211704] linearizableReadLoop","detail":"{readStateIndex:8827; appliedIndex:8826; }","duration":"261.852804ms","start":"2026-09-15T23:01:16.955051Z","end":"2026-09-15T23:01:17.216904Z","steps":["trace[1218211704] 'read index received' (duration: 261.68ms)","trace[1218211704] 'applied index is now lower than readState.Index' (duration: 171.324µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:01:17.21708Z","caller":"traceutil/trace.go:171","msg":"trace[957430569] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"330.690318ms","start":"2026-09-15T23:01:16.886347Z","end":"2026-09-15T23:01:17.217037Z","steps":["trace[957430569] 'process raft request' (duration: 330.373031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:17.217133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.051209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-15T23:01:17.21732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:01:16.886322Z","time spent":"330.926455ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:01:17.217388Z","caller":"traceutil/trace.go:171","msg":"trace[1916443356] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8519; }","duration":"262.354417ms","start":"2026-09-15T23:01:16.95501Z","end":"2026-09-15T23:01:17.217364Z","steps":["trace[1916443356] 'agreement among raft nodes before linearized reading' (duration: 262.04224ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:01:17.386742Z","caller":"traceutil/trace.go:171","msg":"trace[386163883] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"169.517376ms","start":"2026-09-15T23:01:17.2172Z","end":"2026-09-15T23:01:17.386717Z","steps":["trace[386163883] 'read index received' (duration: 167.400932ms)","trace[386163883] 'applied index is now lower than readState.Index' (duration: 2.115304ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:01:17.387044Z","caller":"traceutil/trace.go:171","msg":"trace[179552502] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"196.156619ms","start":"2026-09-15T23:01:17.190875Z","end":"2026-09-15T23:01:17.387032Z","steps":["trace[179552502] 'process raft request' (duration: 193.79176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:17.387252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.60233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:01:17.387313Z","caller":"traceutil/trace.go:171","msg":"trace[932683463] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8520; }","duration":"299.718405ms","start":"2026-09-15T23:01:17.08758Z","end":"2026-09-15T23:01:17.387298Z","steps":["trace[932683463] 'agreement among raft nodes before linearized reading' (duration: 299.590241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:17.387283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.641134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T23:01:17.387375Z","caller":"traceutil/trace.go:171","msg":"trace[2139364679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8520; }","duration":"112.798268ms","start":"2026-09-15T23:01:17.274555Z","end":"2026-09-15T23:01:17.387353Z","steps":["trace[2139364679] 'agreement among raft nodes before linearized reading' (duration: 112.531981ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:01:23.412943Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000229e.wal"} {"level":"info","ts":"2026-09-15T23:01:29.43842Z","caller":"traceutil/trace.go:171","msg":"trace[1744672295] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"174.48166ms","start":"2026-09-15T23:01:29.263914Z","end":"2026-09-15T23:01:29.438395Z","steps":["trace[1744672295] 'process raft request' (duration: 174.335317ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:01:30.160529Z","caller":"traceutil/trace.go:171","msg":"trace[1229796358] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8899; }","duration":"178.152072ms","start":"2026-09-15T23:01:29.982354Z","end":"2026-09-15T23:01:30.160506Z","steps":["trace[1229796358] 'read index received' (duration: 177.930587ms)","trace[1229796358] 'applied index is now lower than readState.Index' (duration: 220.575µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:01:30.160646Z","caller":"traceutil/trace.go:171","msg":"trace[1971003203] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"279.318576ms","start":"2026-09-15T23:01:29.881316Z","end":"2026-09-15T23:01:30.160634Z","steps":["trace[1971003203] 'process raft request' (duration: 279.019009ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:01:30.160692Z","caller":"traceutil/trace.go:171","msg":"trace[2048042815] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"278.66569ms","start":"2026-09-15T23:01:29.881994Z","end":"2026-09-15T23:01:30.160659Z","steps":["trace[2048042815] 'process raft request' (duration: 278.470175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:30.160799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.400111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-15T23:01:30.160856Z","caller":"traceutil/trace.go:171","msg":"trace[1330529943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8591; }","duration":"178.526734ms","start":"2026-09-15T23:01:29.982315Z","end":"2026-09-15T23:01:30.160841Z","steps":["trace[1330529943] 'agreement among raft nodes before linearized reading' (duration: 178.327998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:30.9166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.7448ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-15T23:01:59.952436Z","caller":"traceutil/trace.go:171","msg":"trace[272261810] linearizableReadLoop","detail":"{readStateIndex:9119; appliedIndex:9118; }","duration":"313.881348ms","start":"2026-09-15T23:01:59.638527Z","end":"2026-09-15T23:01:59.952409Z","steps":["trace[272261810] 'read index received' (duration: 313.665022ms)","trace[272261810] 'applied index is now lower than readState.Index' (duration: 215.126µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:01:59.952745Z","caller":"traceutil/trace.go:171","msg":"trace[1886251526] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"369.342536ms","start":"2026-09-15T23:01:59.583374Z","end":"2026-09-15T23:01:59.952717Z","steps":["trace[1886251526] 'process raft request' (duration: 368.887844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:59.952938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:01:59.583358Z","time spent":"369.489539ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:01:59.99629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.344131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:01:59.996376Z","caller":"traceutil/trace.go:171","msg":"trace[1797587769] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8800; }","duration":"331.471355ms","start":"2026-09-15T23:01:59.664883Z","end":"2026-09-15T23:01:59.996355Z","steps":["trace[1797587769] 'agreement among raft nodes before linearized reading' (duration: 287.900769ms)","trace[1797587769] 'count revisions from in-memory index tree' (duration: 43.458792ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:01:59.996352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.805892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-15T23:01:59.996418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:01:59.664867Z","time spent":"331.540977ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-09-15T23:01:59.996435Z","caller":"traceutil/trace.go:171","msg":"trace[564265065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8800; }","duration":"357.942106ms","start":"2026-09-15T23:01:59.638472Z","end":"2026-09-15T23:01:59.996414Z","steps":["trace[564265065] 'agreement among raft nodes before linearized reading' (duration: 314.191206ms)","trace[564265065] 'range keys from in-memory index tree' (duration: 43.529484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:01:59.996476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:01:59.638454Z","time spent":"358.013217ms","remote":"127.0.0.1:57976","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-09-15T23:02:02.502486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.115181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-15T23:02:02.502596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.84393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-15T23:02:02.502663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.237613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhfmg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-15T23:02:02.502701Z","caller":"traceutil/trace.go:171","msg":"trace[217136378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8812; }","duration":"181.001094ms","start":"2026-09-15T23:02:02.321668Z","end":"2026-09-15T23:02:02.502669Z","steps":["trace[217136378] 'range keys from in-memory index tree' (duration: 180.621576ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:02.502708Z","caller":"traceutil/trace.go:171","msg":"trace[700028400] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhfmg; range_end:; response_count:1; response_revision:8812; }","duration":"122.299934ms","start":"2026-09-15T23:02:02.380389Z","end":"2026-09-15T23:02:02.502689Z","steps":["trace[700028400] 'range keys from in-memory index tree' (duration: 122.1324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:02.502737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.759596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-15T23:02:02.50283Z","caller":"traceutil/trace.go:171","msg":"trace[1905885134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8812; }","duration":"150.91083ms","start":"2026-09-15T23:02:02.351901Z","end":"2026-09-15T23:02:02.502812Z","steps":["trace[1905885134] 'range keys from in-memory index tree' (duration: 150.544831ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:02.503191Z","caller":"traceutil/trace.go:171","msg":"trace[2117657383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8812; }","duration":"150.8628ms","start":"2026-09-15T23:02:02.352315Z","end":"2026-09-15T23:02:02.503178Z","steps":["trace[2117657383] 'range keys from in-memory index tree' (duration: 149.936316ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:10.774607Z","caller":"traceutil/trace.go:171","msg":"trace[596173759] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"103.057075ms","start":"2026-09-15T23:02:10.671531Z","end":"2026-09-15T23:02:10.774588Z","steps":["trace[596173759] 'process raft request' (duration: 34.497426ms)","trace[596173759] 'compare' (duration: 68.267042ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:11.258408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.793946ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-15T23:02:11.375032Z","caller":"traceutil/trace.go:171","msg":"trace[1522384094] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"494.792229ms","start":"2026-09-15T23:02:10.880214Z","end":"2026-09-15T23:02:11.375006Z","steps":["trace[1522384094] 'process raft request' (duration: 494.531642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.375191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:10.880191Z","time spent":"494.915813ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:02:11.37527Z","caller":"traceutil/trace.go:171","msg":"trace[963855228] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"329.388341ms","start":"2026-09-15T23:02:11.04583Z","end":"2026-09-15T23:02:11.375218Z","steps":["trace[963855228] 'process raft request' (duration: 329.108353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.375428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:11.045808Z","time spent":"329.527304ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:02:12.557693Z","caller":"traceutil/trace.go:171","msg":"trace[1985623157] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"178.999453ms","start":"2026-09-15T23:02:12.378658Z","end":"2026-09-15T23:02:12.557658Z","steps":["trace[1985623157] 'process raft request' (duration: 178.761348ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:12.770884Z","caller":"traceutil/trace.go:171","msg":"trace[4650597] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"133.969951ms","start":"2026-09-15T23:02:12.636893Z","end":"2026-09-15T23:02:12.770863Z","steps":["trace[4650597] 'process raft request' (duration: 133.919119ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:12.770934Z","caller":"traceutil/trace.go:171","msg":"trace[2128420260] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"144.357964ms","start":"2026-09-15T23:02:12.626536Z","end":"2026-09-15T23:02:12.770894Z","steps":["trace[2128420260] 'process raft request' (duration: 74.306856ms)","trace[2128420260] 'compare' (duration: 69.830913ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:12.771238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.132696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-15T23:02:12.770953Z","caller":"traceutil/trace.go:171","msg":"trace[2130026775] linearizableReadLoop","detail":"{readStateIndex:9191; appliedIndex:9190; }","duration":"137.840549ms","start":"2026-09-15T23:02:12.63308Z","end":"2026-09-15T23:02:12.77092Z","steps":["trace[2130026775] 'read index received' (duration: 67.805751ms)","trace[2130026775] 'applied index is now lower than readState.Index' (duration: 70.030798ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:02:12.771289Z","caller":"traceutil/trace.go:171","msg":"trace[1894910546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8870; }","duration":"138.199758ms","start":"2026-09-15T23:02:12.633074Z","end":"2026-09-15T23:02:12.771274Z","steps":["trace[1894910546] 'agreement among raft nodes before linearized reading' (duration: 137.962142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:12.771423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.81241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhfmg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-15T23:02:12.77146Z","caller":"traceutil/trace.go:171","msg":"trace[1009739576] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhfmg; range_end:; response_count:1; response_revision:8870; }","duration":"130.863112ms","start":"2026-09-15T23:02:12.640586Z","end":"2026-09-15T23:02:12.771449Z","steps":["trace[1009739576] 'agreement among raft nodes before linearized reading' (duration: 130.754989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:13.893465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.975823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:02:13.893901Z","caller":"traceutil/trace.go:171","msg":"trace[1802318138] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"234.460202ms","start":"2026-09-15T23:02:13.659422Z","end":"2026-09-15T23:02:13.893882Z","steps":["trace[1802318138] 'process raft request' (duration: 234.316728ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:13.893964Z","caller":"traceutil/trace.go:171","msg":"trace[1876227003] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8883; }","duration":"235.400635ms","start":"2026-09-15T23:02:13.658527Z","end":"2026-09-15T23:02:13.893928Z","steps":["trace[1876227003] 'process raft request' (duration: 44.764795ms)","trace[1876227003] 'compare' (duration: 189.86181ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:03:29.701058Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6ec433552cd258e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-15T23:03:29.70902Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-15T23:03:29.709165Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-15T23:05:22.204277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8030} {"level":"info","ts":"2026-09-15T23:05:22.276166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8030,"took":"69.493107ms","hash":1042976404,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T23:05:22.276259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1042976404,"revision":8030,"compact-revision":5876} {"level":"info","ts":"2026-09-15T23:09:19.281899Z","caller":"traceutil/trace.go:171","msg":"trace[1145960736] linearizableReadLoop","detail":"{readStateIndex:12575; appliedIndex:12574; }","duration":"305.926929ms","start":"2026-09-15T23:09:18.975944Z","end":"2026-09-15T23:09:19.281871Z","steps":["trace[1145960736] 'read index received' (duration: 289.680794ms)","trace[1145960736] 'applied index is now lower than readState.Index' (duration: 16.245095ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:09:19.282002Z","caller":"traceutil/trace.go:171","msg":"trace[1552782143] transaction","detail":"{read_only:false; response_revision:12150; number_of_response:1; }","duration":"307.365874ms","start":"2026-09-15T23:09:18.974608Z","end":"2026-09-15T23:09:19.281973Z","steps":["trace[1552782143] 'process raft request' (duration: 290.881484ms)","trace[1552782143] 'compare' (duration: 16.229324ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:09:19.282109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.136543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-15T23:09:19.282141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:09:18.974586Z","time spent":"307.477247ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:09:19.282171Z","caller":"traceutil/trace.go:171","msg":"trace[1717376785] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:12150; }","duration":"306.249736ms","start":"2026-09-15T23:09:18.975906Z","end":"2026-09-15T23:09:19.282156Z","steps":["trace[1717376785] 'agreement among raft nodes before linearized reading' (duration: 306.120493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:09:19.282223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:09:18.975894Z","time spent":"306.314288ms","remote":"127.0.0.1:47642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-09-15T23:09:19.335875Z","caller":"traceutil/trace.go:171","msg":"trace[970287235] transaction","detail":"{read_only:false; response_revision:12151; number_of_response:1; }","duration":"358.970171ms","start":"2026-09-15T23:09:18.976881Z","end":"2026-09-15T23:09:19.335851Z","steps":["trace[970287235] 'process raft request' (duration: 358.768705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:09:19.336003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:09:18.976861Z","time spent":"359.074313ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:09:19.336221Z","caller":"traceutil/trace.go:171","msg":"trace[1628484292] transaction","detail":"{read_only:false; response_revision:12152; number_of_response:1; }","duration":"327.678213ms","start":"2026-09-15T23:09:19.008503Z","end":"2026-09-15T23:09:19.336182Z","steps":["trace[1628484292] 'process raft request' (duration: 327.282453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:09:19.336385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:09:19.008478Z","time spent":"327.821686ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:09:19.336411Z","caller":"traceutil/trace.go:171","msg":"trace[67810970] transaction","detail":"{read_only:false; response_revision:12153; number_of_response:1; }","duration":"221.327222ms","start":"2026-09-15T23:09:19.114935Z","end":"2026-09-15T23:09:19.336262Z","steps":["trace[67810970] 'process raft request' (duration: 221.120817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:09:19.346774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.314998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:09:19.346877Z","caller":"traceutil/trace.go:171","msg":"trace[1986766402] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:12154; }","duration":"275.464182ms","start":"2026-09-15T23:09:19.071394Z","end":"2026-09-15T23:09:19.346858Z","steps":["trace[1986766402] 'agreement among raft nodes before linearized reading' (duration: 275.305118ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:22.212071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10423} {"level":"info","ts":"2026-09-15T23:10:22.304263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10423,"took":"89.197953ms","hash":3772732494,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":26148864,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-15T23:10:22.304337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3772732494,"revision":10423,"compact-revision":8030} {"level":"info","ts":"2026-09-15T23:15:22.22232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12593} {"level":"info","ts":"2026-09-15T23:15:22.310512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12593,"took":"85.606945ms","hash":1356437265,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":27279360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-15T23:15:22.310586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1356437265,"revision":12593,"compact-revision":10423} {"level":"info","ts":"2026-09-15T23:15:52.921783Z","caller":"traceutil/trace.go:171","msg":"trace[66940755] transaction","detail":"{read_only:false; response_revision:14772; number_of_response:1; }","duration":"130.23963ms","start":"2026-09-15T23:15:52.791513Z","end":"2026-09-15T23:15:52.921752Z","steps":["trace[66940755] 'process raft request' (duration: 129.984933ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:15:52.937433Z","caller":"traceutil/trace.go:171","msg":"trace[1453035637] transaction","detail":"{read_only:false; response_revision:14773; number_of_response:1; }","duration":"130.33548ms","start":"2026-09-15T23:15:52.807067Z","end":"2026-09-15T23:15:52.937403Z","steps":["trace[1453035637] 'process raft request' (duration: 130.191187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:00.417731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.20461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:16:00.417892Z","caller":"traceutil/trace.go:171","msg":"trace[5683659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14823; }","duration":"123.405515ms","start":"2026-09-15T23:16:00.294456Z","end":"2026-09-15T23:16:00.417862Z","steps":["trace[5683659] 'range keys from in-memory index tree' (duration: 123.106487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:01.223606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.751737ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-15T23:16:01.249083Z","caller":"traceutil/trace.go:171","msg":"trace[1867682034] transaction","detail":"{read_only:false; response_revision:14828; number_of_response:1; }","duration":"304.763033ms","start":"2026-09-15T23:16:00.944277Z","end":"2026-09-15T23:16:01.24904Z","steps":["trace[1867682034] 'process raft request' (duration: 304.443505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:01.249384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:00.944247Z","time spent":"304.971618ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:16:01.447595Z","caller":"traceutil/trace.go:171","msg":"trace[1361434963] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"483.244468ms","start":"2026-09-15T23:16:00.964315Z","end":"2026-09-15T23:16:01.447559Z","steps":["trace[1361434963] 'process raft request' (duration: 481.749239ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:16:01.447758Z","caller":"traceutil/trace.go:171","msg":"trace[1151399936] linearizableReadLoop","detail":"{readStateIndex:15353; appliedIndex:15351; }","duration":"159.859311ms","start":"2026-09-15T23:16:01.287869Z","end":"2026-09-15T23:16:01.447728Z","steps":["trace[1151399936] 'read index received' (duration: 158.206769ms)","trace[1151399936] 'applied index is now lower than readState.Index' (duration: 1.651192ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:16:01.447942Z","caller":"traceutil/trace.go:171","msg":"trace[751266282] transaction","detail":"{read_only:false; response_revision:14830; number_of_response:1; }","duration":"184.836916ms","start":"2026-09-15T23:16:01.263087Z","end":"2026-09-15T23:16:01.447924Z","steps":["trace[751266282] 'process raft request' (duration: 184.401275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:01.448017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.133999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:16:01.448085Z","caller":"traceutil/trace.go:171","msg":"trace[1912283625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14830; }","duration":"151.239552ms","start":"2026-09-15T23:16:01.296831Z","end":"2026-09-15T23:16:01.44807Z","steps":["trace[1912283625] 'agreement among raft nodes before linearized reading' (duration: 151.105608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:01.44824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.348314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-15T23:16:01.448337Z","caller":"traceutil/trace.go:171","msg":"trace[1824154878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14830; }","duration":"160.501808ms","start":"2026-09-15T23:16:01.287816Z","end":"2026-09-15T23:16:01.448318Z","steps":["trace[1824154878] 'agreement among raft nodes before linearized reading' (duration: 160.2154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:01.447855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:00.964288Z","time spent":"483.410742ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:16:25.269179Z","caller":"traceutil/trace.go:171","msg":"trace[1790631013] linearizableReadLoop","detail":"{readStateIndex:15681; appliedIndex:15680; }","duration":"307.78486ms","start":"2026-09-15T23:16:24.961359Z","end":"2026-09-15T23:16:25.269144Z","steps":["trace[1790631013] 'read index received' (duration: 280.103876ms)","trace[1790631013] 'applied index is now lower than readState.Index' (duration: 27.679214ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:16:25.269329Z","caller":"traceutil/trace.go:171","msg":"trace[154575569] transaction","detail":"{read_only:false; response_revision:15147; number_of_response:1; }","duration":"333.602846ms","start":"2026-09-15T23:16:24.93565Z","end":"2026-09-15T23:16:25.269253Z","steps":["trace[154575569] 'process raft request' (duration: 305.84762ms)","trace[154575569] 'compare' (duration: 27.432598ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:16:25.269462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.077527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab7d4710-8e99-43e8-8d5e-3ead6a1de87d\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-09-15T23:16:25.269509Z","caller":"traceutil/trace.go:171","msg":"trace[1615760258] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab7d4710-8e99-43e8-8d5e-3ead6a1de87d; range_end:; response_count:1; response_revision:15147; }","duration":"308.179799ms","start":"2026-09-15T23:16:24.961311Z","end":"2026-09-15T23:16:25.26949Z","steps":["trace[1615760258] 'agreement among raft nodes before linearized reading' (duration: 307.969194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:25.269546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:24.961288Z","time spent":"308.248651ms","remote":"127.0.0.1:57886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab7d4710-8e99-43e8-8d5e-3ead6a1de87d\" "} {"level":"warn","ts":"2026-09-15T23:16:25.269859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:24.935624Z","time spent":"333.82754ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:17:52.233407Z","caller":"traceutil/trace.go:171","msg":"trace[1570766820] linearizableReadLoop","detail":"{readStateIndex:16518; appliedIndex:16517; }","duration":"106.749183ms","start":"2026-09-15T23:17:52.12664Z","end":"2026-09-15T23:17:52.23339Z","steps":["trace[1570766820] 'read index received' (duration: 106.692111ms)","trace[1570766820] 'applied index is now lower than readState.Index' (duration: 56.262µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:17:52.233663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.01717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-15T23:17:52.23364Z","caller":"traceutil/trace.go:171","msg":"trace[416451174] transaction","detail":"{read_only:false; response_revision:15961; number_of_response:1; }","duration":"108.739693ms","start":"2026-09-15T23:17:52.12473Z","end":"2026-09-15T23:17:52.23347Z","steps":["trace[416451174] 'process raft request' (duration: 108.557228ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:52.233697Z","caller":"traceutil/trace.go:171","msg":"trace[1469634681] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15961; }","duration":"107.107042ms","start":"2026-09-15T23:17:52.12658Z","end":"2026-09-15T23:17:52.233687Z","steps":["trace[1469634681] 'agreement among raft nodes before linearized reading' (duration: 106.898896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:52.238915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.234361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-15T23:17:52.239005Z","caller":"traceutil/trace.go:171","msg":"trace[1692200669] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15962; }","duration":"110.349724ms","start":"2026-09-15T23:17:52.128639Z","end":"2026-09-15T23:17:52.238989Z","steps":["trace[1692200669] 'agreement among raft nodes before linearized reading' (duration: 110.096738ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:52.239275Z","caller":"traceutil/trace.go:171","msg":"trace[739165479] transaction","detail":"{read_only:false; response_revision:15962; number_of_response:1; }","duration":"112.625723ms","start":"2026-09-15T23:17:52.126638Z","end":"2026-09-15T23:17:52.239264Z","steps":["trace[739165479] 'process raft request' (duration: 111.970596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:52.23985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.159331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-15T23:17:52.239891Z","caller":"traceutil/trace.go:171","msg":"trace[1745361749] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15962; }","duration":"103.231593ms","start":"2026-09-15T23:17:52.13665Z","end":"2026-09-15T23:17:52.239882Z","steps":["trace[1745361749] 'agreement among raft nodes before linearized reading' (duration: 103.12303ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:57.882675Z","caller":"traceutil/trace.go:171","msg":"trace[114257513] transaction","detail":"{read_only:false; response_revision:15987; number_of_response:1; }","duration":"147.19989ms","start":"2026-09-15T23:17:57.735453Z","end":"2026-09-15T23:17:57.882653Z","steps":["trace[114257513] 'process raft request' (duration: 147.064947ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:57.907037Z","caller":"traceutil/trace.go:171","msg":"trace[537380809] linearizableReadLoop","detail":"{readStateIndex:16546; appliedIndex:16544; }","duration":"116.213714ms","start":"2026-09-15T23:17:57.790801Z","end":"2026-09-15T23:17:57.907014Z","steps":["trace[537380809] 'read index received' (duration: 91.645049ms)","trace[537380809] 'applied index is now lower than readState.Index' (duration: 24.567615ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:17:57.907349Z","caller":"traceutil/trace.go:171","msg":"trace[1647420794] transaction","detail":"{read_only:false; response_revision:15988; number_of_response:1; }","duration":"169.24539ms","start":"2026-09-15T23:17:57.73809Z","end":"2026-09-15T23:17:57.907335Z","steps":["trace[1647420794] 'process raft request' (duration: 168.802768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:57.907914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.112416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l7lk8\" ","response":"range_response_count:1 size:22017"} {"level":"info","ts":"2026-09-15T23:17:57.907977Z","caller":"traceutil/trace.go:171","msg":"trace[1667233875] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l7lk8; range_end:; response_count:1; response_revision:15988; }","duration":"117.214289ms","start":"2026-09-15T23:17:57.790745Z","end":"2026-09-15T23:17:57.90796Z","steps":["trace[1667233875] 'agreement among raft nodes before linearized reading' (duration: 116.925081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:58.778107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.370465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhfmg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-15T23:17:58.778167Z","caller":"traceutil/trace.go:171","msg":"trace[214789873] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhfmg; range_end:; response_count:1; response_revision:15993; }","duration":"182.484627ms","start":"2026-09-15T23:17:58.59567Z","end":"2026-09-15T23:17:58.778155Z","steps":["trace[214789873] 'range keys from in-memory index tree' (duration: 182.197851ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:20:22.229619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14607} {"level":"info","ts":"2026-09-15T23:20:22.324445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14607,"took":"91.831614ms","hash":1369689689,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-15T23:20:22.324515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1369689689,"revision":14607,"compact-revision":12593} {"level":"info","ts":"2026-09-15T23:23:52.300782Z","caller":"traceutil/trace.go:171","msg":"trace[313345995] linearizableReadLoop","detail":"{readStateIndex:19249; appliedIndex:19248; }","duration":"120.596506ms","start":"2026-09-15T23:23:52.180162Z","end":"2026-09-15T23:23:52.300759Z","steps":["trace[313345995] 'read index received' (duration: 120.483313ms)","trace[313345995] 'applied index is now lower than readState.Index' (duration: 111.993µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:23:52.300984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.801132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-15T23:23:52.301022Z","caller":"traceutil/trace.go:171","msg":"trace[1307062600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18604; }","duration":"120.894744ms","start":"2026-09-15T23:23:52.180117Z","end":"2026-09-15T23:23:52.301012Z","steps":["trace[1307062600] 'agreement among raft nodes before linearized reading' (duration: 120.74046ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:23:52.301151Z","caller":"traceutil/trace.go:171","msg":"trace[278492449] transaction","detail":"{read_only:false; response_revision:18604; number_of_response:1; }","duration":"130.285133ms","start":"2026-09-15T23:23:52.170808Z","end":"2026-09-15T23:23:52.301093Z","steps":["trace[278492449] 'process raft request' (duration: 129.783209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:58.551428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.016059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-15T23:23:58.551515Z","caller":"traceutil/trace.go:171","msg":"trace[1470841375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18639; }","duration":"221.179423ms","start":"2026-09-15T23:23:58.330314Z","end":"2026-09-15T23:23:58.551493Z","steps":["trace[1470841375] 'range keys from in-memory index tree' (duration: 220.721672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:58.551526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.074087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-15T23:23:58.551597Z","caller":"traceutil/trace.go:171","msg":"trace[1803869003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18639; }","duration":"215.21268ms","start":"2026-09-15T23:23:58.336367Z","end":"2026-09-15T23:23:58.55158Z","steps":["trace[1803869003] 'range keys from in-memory index tree' (duration: 214.883573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:58.91578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.19777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-15T23:23:58.915861Z","caller":"traceutil/trace.go:171","msg":"trace[789966550] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:18641; }","duration":"248.329493ms","start":"2026-09-15T23:23:58.667512Z","end":"2026-09-15T23:23:58.915842Z","steps":["trace[789966550] 'count revisions from in-memory index tree' (duration: 248.118858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:58.916685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.137619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l7lk8\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-09-15T23:23:58.91673Z","caller":"traceutil/trace.go:171","msg":"trace[1350809420] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l7lk8; range_end:; response_count:1; response_revision:18641; }","duration":"108.210871ms","start":"2026-09-15T23:23:58.808509Z","end":"2026-09-15T23:23:58.916719Z","steps":["trace[1350809420] 'range keys from in-memory index tree' (duration: 107.968655ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:23:59.553122Z","caller":"traceutil/trace.go:171","msg":"trace[1973929958] linearizableReadLoop","detail":"{readStateIndex:19288; appliedIndex:19287; }","duration":"272.55554ms","start":"2026-09-15T23:23:59.280536Z","end":"2026-09-15T23:23:59.553091Z","steps":["trace[1973929958] 'read index received' (duration: 272.443507ms)","trace[1973929958] 'applied index is now lower than readState.Index' (duration: 110.862µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:23:59.553251Z","caller":"traceutil/trace.go:171","msg":"trace[1025341041] transaction","detail":"{read_only:false; response_revision:18642; number_of_response:1; }","duration":"427.586381ms","start":"2026-09-15T23:23:59.125633Z","end":"2026-09-15T23:23:59.553219Z","steps":["trace[1025341041] 'process raft request' (duration: 427.227342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:59.553395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.848426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-15T23:23:59.553405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:23:59.125611Z","time spent":"427.707753ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:23:59.55344Z","caller":"traceutil/trace.go:171","msg":"trace[774595909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18642; }","duration":"272.95905ms","start":"2026-09-15T23:23:59.280468Z","end":"2026-09-15T23:23:59.553427Z","steps":["trace[774595909] 'agreement among raft nodes before linearized reading' (duration: 272.763615ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:23:59.572009Z","caller":"traceutil/trace.go:171","msg":"trace[420477938] transaction","detail":"{read_only:false; response_revision:18643; number_of_response:1; }","duration":"290.373012ms","start":"2026-09-15T23:23:59.281601Z","end":"2026-09-15T23:23:59.571974Z","steps":["trace[420477938] 'process raft request' (duration: 290.090535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:59.572129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.335495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:23:59.572118Z","caller":"traceutil/trace.go:171","msg":"trace[1407229710] transaction","detail":"{read_only:false; response_revision:18645; number_of_response:1; }","duration":"178.401075ms","start":"2026-09-15T23:23:59.393691Z","end":"2026-09-15T23:23:59.572093Z","steps":["trace[1407229710] 'process raft request' (duration: 178.216311ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:23:59.57217Z","caller":"traceutil/trace.go:171","msg":"trace[1523208047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18645; }","duration":"274.428547ms","start":"2026-09-15T23:23:59.297731Z","end":"2026-09-15T23:23:59.572159Z","steps":["trace[1523208047] 'agreement among raft nodes before linearized reading' (duration: 274.326594ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:23:59.572006Z","caller":"traceutil/trace.go:171","msg":"trace[2067573370] transaction","detail":"{read_only:false; response_revision:18644; number_of_response:1; }","duration":"184.010398ms","start":"2026-09-15T23:23:59.387969Z","end":"2026-09-15T23:23:59.571979Z","steps":["trace[2067573370] 'process raft request' (duration: 183.894116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:59.572372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.829559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tl4n4\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-09-15T23:23:59.572414Z","caller":"traceutil/trace.go:171","msg":"trace[524841350] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tl4n4; range_end:; response_count:1; response_revision:18645; }","duration":"175.914042ms","start":"2026-09-15T23:23:59.396488Z","end":"2026-09-15T23:23:59.572402Z","steps":["trace[524841350] 'agreement among raft nodes before linearized reading' (duration: 175.739988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:23:59.572493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.797892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-15T23:23:59.572588Z","caller":"traceutil/trace.go:171","msg":"trace[607822881] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:18645; }","duration":"157.986778ms","start":"2026-09-15T23:23:59.414578Z","end":"2026-09-15T23:23:59.572564Z","steps":["trace[607822881] 'agreement among raft nodes before linearized reading' (duration: 157.619908ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:25:00.125801Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6ec433552cd258e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-15T23:25:00.134655Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-15T23:25:00.134815Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-15T23:25:22.237292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17035} {"level":"info","ts":"2026-09-15T23:25:22.342836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17035,"took":"100.841803ms","hash":898480607,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":33394688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-15T23:25:22.342914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":898480607,"revision":17035,"compact-revision":14607} {"level":"info","ts":"2026-09-15T23:29:43.614178Z","caller":"traceutil/trace.go:171","msg":"trace[1399121708] transaction","detail":"{read_only:false; response_revision:21315; number_of_response:1; }","duration":"175.308461ms","start":"2026-09-15T23:29:43.438819Z","end":"2026-09-15T23:29:43.614127Z","steps":["trace[1399121708] 'process raft request' (duration: 175.123036ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:29:45.026749Z","caller":"traceutil/trace.go:171","msg":"trace[822835524] transaction","detail":"{read_only:false; response_revision:21318; number_of_response:1; }","duration":"164.622068ms","start":"2026-09-15T23:29:44.8621Z","end":"2026-09-15T23:29:45.026722Z","steps":["trace[822835524] 'process raft request' (duration: 164.467774ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:29:45.356341Z","caller":"traceutil/trace.go:171","msg":"trace[405340614] transaction","detail":"{read_only:false; response_revision:21320; number_of_response:1; }","duration":"475.25155ms","start":"2026-09-15T23:29:44.881059Z","end":"2026-09-15T23:29:45.35631Z","steps":["trace[405340614] 'process raft request' (duration: 475.157567ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:29:45.356429Z","caller":"traceutil/trace.go:171","msg":"trace[894291152] transaction","detail":"{read_only:false; response_revision:21319; number_of_response:1; }","duration":"485.260535ms","start":"2026-09-15T23:29:44.87112Z","end":"2026-09-15T23:29:45.356381Z","steps":["trace[894291152] 'process raft request' (duration: 484.923287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.356525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:44.881033Z","time spent":"475.403634ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:29:45.356563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:44.871099Z","time spent":"485.389688ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:29:45.689465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.17626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:29:45.689543Z","caller":"traceutil/trace.go:171","msg":"trace[1497290373] linearizableReadLoop","detail":"{readStateIndex:22053; appliedIndex:22050; }","duration":"463.582022ms","start":"2026-09-15T23:29:45.225946Z","end":"2026-09-15T23:29:45.689528Z","steps":["trace[1497290373] 'read index received' (duration: 130.039126ms)","trace[1497290373] 'applied index is now lower than readState.Index' (duration: 333.541386ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:29:45.68964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.692055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:29:45.6896Z","caller":"traceutil/trace.go:171","msg":"trace[559518036] transaction","detail":"{read_only:false; response_revision:21321; number_of_response:1; }","duration":"553.767643ms","start":"2026-09-15T23:29:45.135787Z","end":"2026-09-15T23:29:45.689555Z","steps":["trace[559518036] 'process raft request' (duration: 365.320777ms)","trace[559518036] 'compare' (duration: 187.981974ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:29:45.689719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.199008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-15T23:29:45.689777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.486908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-09-15T23:29:45.689803Z","caller":"traceutil/trace.go:171","msg":"trace[425501815] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:21321; }","duration":"290.55879ms","start":"2026-09-15T23:29:45.399236Z","end":"2026-09-15T23:29:45.689795Z","steps":["trace[425501815] 'agreement among raft nodes before linearized reading' (duration: 290.460647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.689881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:45.135757Z","time spent":"553.958247ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:29:45.689916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.397786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-15T23:29:45.689943Z","caller":"traceutil/trace.go:171","msg":"trace[1564273273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21321; }","duration":"341.457989ms","start":"2026-09-15T23:29:45.348476Z","end":"2026-09-15T23:29:45.689934Z","steps":["trace[1564273273] 'agreement among raft nodes before linearized reading' (duration: 341.342125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.689971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:45.348461Z","time spent":"341.50324ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-15T23:29:45.689795Z","caller":"traceutil/trace.go:171","msg":"trace[1811688855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21321; }","duration":"393.325001ms","start":"2026-09-15T23:29:45.296451Z","end":"2026-09-15T23:29:45.689776Z","steps":["trace[1811688855] 'agreement among raft nodes before linearized reading' (duration: 393.185718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.690096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:45.296433Z","time spent":"393.65302ms","remote":"127.0.0.1:57684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-15T23:29:45.690142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.446248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-15T23:29:45.690166Z","caller":"traceutil/trace.go:171","msg":"trace[1681273045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21321; }","duration":"350.510629ms","start":"2026-09-15T23:29:45.339647Z","end":"2026-09-15T23:29:45.690158Z","steps":["trace[1681273045] 'agreement among raft nodes before linearized reading' (duration: 350.433378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.690188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:45.339628Z","time spent":"350.554651ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-15T23:29:45.689669Z","caller":"traceutil/trace.go:171","msg":"trace[1341784955] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:21321; }","duration":"463.766358ms","start":"2026-09-15T23:29:45.225892Z","end":"2026-09-15T23:29:45.689658Z","steps":["trace[1341784955] 'agreement among raft nodes before linearized reading' (duration: 463.710287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:29:45.690257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:29:45.22586Z","time spent":"464.375813ms","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-09-15T23:29:45.823617Z","caller":"traceutil/trace.go:171","msg":"trace[380839910] transaction","detail":"{read_only:false; response_revision:21324; number_of_response:1; }","duration":"127.674416ms","start":"2026-09-15T23:29:45.69591Z","end":"2026-09-15T23:29:45.823585Z","steps":["trace[380839910] 'process raft request' (duration: 127.604494ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:29:45.823642Z","caller":"traceutil/trace.go:171","msg":"trace[916262321] transaction","detail":"{read_only:false; response_revision:21323; number_of_response:1; }","duration":"129.182575ms","start":"2026-09-15T23:29:45.694415Z","end":"2026-09-15T23:29:45.823598Z","steps":["trace[916262321] 'process raft request' (duration: 127.445551ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:22.249867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19517} {"level":"info","ts":"2026-09-15T23:30:22.382358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19517,"took":"126.514957ms","hash":3663429876,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":28725248,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-15T23:30:22.382457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3663429876,"revision":19517,"compact-revision":17035} {"level":"info","ts":"2026-09-15T23:33:54.163662Z","caller":"traceutil/trace.go:171","msg":"trace[1773254965] linearizableReadLoop","detail":"{readStateIndex:24681; appliedIndex:24680; }","duration":"302.254434ms","start":"2026-09-15T23:33:53.86136Z","end":"2026-09-15T23:33:54.163615Z","steps":["trace[1773254965] 'read index received' (duration: 302.054489ms)","trace[1773254965] 'applied index is now lower than readState.Index' (duration: 198.655µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:33:54.163904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.524131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-15T23:33:54.163958Z","caller":"traceutil/trace.go:171","msg":"trace[1384731396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23873; }","duration":"302.631103ms","start":"2026-09-15T23:33:53.86131Z","end":"2026-09-15T23:33:54.163941Z","steps":["trace[1384731396] 'agreement among raft nodes before linearized reading' (duration: 302.430948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:33:54.163998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:33:53.861294Z","time spent":"302.695294ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-15T23:33:54.164415Z","caller":"traceutil/trace.go:171","msg":"trace[219755847] transaction","detail":"{read_only:false; response_revision:23873; number_of_response:1; }","duration":"340.644207ms","start":"2026-09-15T23:33:53.823735Z","end":"2026-09-15T23:33:54.164379Z","steps":["trace[219755847] 'process raft request' (duration: 339.701483ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:33:54.164595Z","caller":"traceutil/trace.go:171","msg":"trace[1991582064] transaction","detail":"{read_only:false; response_revision:23874; number_of_response:1; }","duration":"195.929478ms","start":"2026-09-15T23:33:53.968646Z","end":"2026-09-15T23:33:54.164575Z","steps":["trace[1991582064] 'process raft request' (duration: 195.648731ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:33:54.164734Z","caller":"traceutil/trace.go:171","msg":"trace[716241062] transaction","detail":"{read_only:false; response_revision:23875; number_of_response:1; }","duration":"127.900253ms","start":"2026-09-15T23:33:54.036817Z","end":"2026-09-15T23:33:54.164717Z","steps":["trace[716241062] 'process raft request' (duration: 127.565365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:33:54.164892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:33:53.823718Z","time spent":"340.884603ms","remote":"127.0.0.1:57976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:35:22.257893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21628} {"level":"info","ts":"2026-09-15T23:35:22.380536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21628,"took":"117.192922ms","hash":1285497556,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":43556864,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-15T23:35:22.380605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1285497556,"revision":21628,"compact-revision":19517} {"level":"info","ts":"2026-09-15T23:40:11.330877Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006d0a.wal"} {"level":"info","ts":"2026-09-15T23:40:22.268698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24845} {"level":"info","ts":"2026-09-15T23:40:22.444914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24845,"took":"172.224849ms","hash":942044739,"current-db-size-bytes":58060800,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-15T23:40:22.444986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":942044739,"revision":24845,"compact-revision":21628}