{"level":"warn","ts":"2026-10-14T17:43:17.299157Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:43:17.29931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.169: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.169:2380","--initial-cluster=instance=https://162.253.55.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.169: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-10-14T17:43:17.299447Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:43:17.299472Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.169:2380"]} {"level":"info","ts":"2026-10-14T17:43:17.299507Z","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-10-14T17:43:17.300838Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"]} {"level":"info","ts":"2026-10-14T17:43:17.300986Z","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.169:2380"],"listen-peer-urls":["https://162.253.55.169:2380"],"advertise-client-urls":["https://162.253.55.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.169: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-10-14T17:43:17.306659Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.228064ms"} {"level":"info","ts":"2026-10-14T17:43:17.316399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b856fd30c5dc1b8","cluster-id":"9989ba7b656496c6"} {"level":"info","ts":"2026-10-14T17:43:17.316552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:43:17.316583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:43:17.316596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b856fd30c5dc1b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:43:17.316607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:43:17.316651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=(830192658555978168)"} {"level":"warn","ts":"2026-10-14T17:43:17.325394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:43:17.327093Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:43:17.32942Z","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-10-14T17:43:17.333186Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b856fd30c5dc1b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:43:17.333502Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b856fd30c5dc1b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:43:17.333759Z","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-10-14T17:43:17.333915Z","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-10-14T17:43:17.33394Z","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-10-14T17:43:17.335051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=(830192658555978168)"} {"level":"info","ts":"2026-10-14T17:43:17.335302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9989ba7b656496c6","local-member-id":"b856fd30c5dc1b8","added-peer-id":"b856fd30c5dc1b8","added-peer-peer-urls":["https://162.253.55.169:2380"]} {"level":"info","ts":"2026-10-14T17:43:17.339006Z","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-10-14T17:43:17.33906Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.169:2380"} {"level":"info","ts":"2026-10-14T17:43:17.33924Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.169:2380"} {"level":"info","ts":"2026-10-14T17:43:17.339554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b856fd30c5dc1b8","initial-advertise-peer-urls":["https://162.253.55.169:2380"],"listen-peer-urls":["https://162.253.55.169:2380"],"advertise-client-urls":["https://162.253.55.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:43:17.339641Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:43:18.117594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:43:18.117658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:43:18.117704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 received MsgPreVoteResp from b856fd30c5dc1b8 at term 1"} {"level":"info","ts":"2026-10-14T17:43:18.117731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:43:18.117769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 received MsgVoteResp from b856fd30c5dc1b8 at term 2"} {"level":"info","ts":"2026-10-14T17:43:18.11779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:43:18.117808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b856fd30c5dc1b8 elected leader b856fd30c5dc1b8 at term 2"} {"level":"info","ts":"2026-10-14T17:43:18.119091Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:43:18.120281Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b856fd30c5dc1b8","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.169:2379]}","request-path":"/0/members/b856fd30c5dc1b8/attributes","cluster-id":"9989ba7b656496c6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:43:18.120292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:43:18.120327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:43:18.120737Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:43:18.120798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:43:18.124848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:43:18.124865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.169:2379"} {"level":"info","ts":"2026-10-14T17:43:18.125831Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9989ba7b656496c6","local-member-id":"b856fd30c5dc1b8","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:43:18.125953Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:43:18.126Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:43:29.94418Z","caller":"traceutil/trace.go:171","msg":"trace[1090241133] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"155.173196ms","start":"2026-10-14T17:43:29.788985Z","end":"2026-10-14T17:43:29.944158Z","steps":["trace[1090241133] 'process raft request' (duration: 155.017613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:30.429138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.072777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:43:30.429322Z","caller":"traceutil/trace.go:171","msg":"trace[1073121604] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"253.444015ms","start":"2026-10-14T17:43:30.175844Z","end":"2026-10-14T17:43:30.429289Z","steps":["trace[1073121604] 'process raft request' (duration: 107.482739ms)","trace[1073121604] 'compare' (duration: 144.909813ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:43:58.750802Z","caller":"traceutil/trace.go:171","msg":"trace[1573804984] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"142.626284ms","start":"2026-10-14T17:43:58.608144Z","end":"2026-10-14T17:43:58.750771Z","steps":["trace[1573804984] 'process raft request' (duration: 142.484271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:09.116472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.284413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:44:09.116595Z","caller":"traceutil/trace.go:171","msg":"trace[843382046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"121.472158ms","start":"2026-10-14T17:44:08.995102Z","end":"2026-10-14T17:44:09.116574Z","steps":["trace[843382046] 'range keys from in-memory index tree' (duration: 121.194821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:09.116472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.980712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T17:44:09.116706Z","caller":"traceutil/trace.go:171","msg":"trace[1719721666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"202.264219ms","start":"2026-10-14T17:44:08.91442Z","end":"2026-10-14T17:44:09.116684Z","steps":["trace[1719721666] 'range keys from in-memory index tree' (duration: 201.867169ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:09.51602Z","caller":"traceutil/trace.go:171","msg":"trace[571501924] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"229.715343ms","start":"2026-10-14T17:44:09.286274Z","end":"2026-10-14T17:44:09.515989Z","steps":["trace[571501924] 'process raft request' (duration: 157.715906ms)","trace[571501924] 'compare' (duration: 71.855784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:44:10.182275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.15532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q6xqx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T17:44:10.182359Z","caller":"traceutil/trace.go:171","msg":"trace[472372525] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q6xqx; range_end:; response_count:1; response_revision:609; }","duration":"468.292743ms","start":"2026-10-14T17:44:09.714047Z","end":"2026-10-14T17:44:10.18234Z","steps":["trace[472372525] 'range keys from in-memory index tree' (duration: 467.960125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:10.182397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:44:09.71403Z","time spent":"468.358144ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-q6xqx\" "} {"level":"info","ts":"2026-10-14T17:44:10.185517Z","caller":"traceutil/trace.go:171","msg":"trace[1803542867] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"217.551755ms","start":"2026-10-14T17:44:09.967902Z","end":"2026-10-14T17:44:10.185454Z","steps":["trace[1803542867] 'process raft request' (duration: 215.380578ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:25.861752Z","caller":"traceutil/trace.go:171","msg":"trace[884835978] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"271.408363ms","start":"2026-10-14T17:44:25.590311Z","end":"2026-10-14T17:44:25.861719Z","steps":["trace[884835978] 'process raft request' (duration: 271.180558ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:26.066373Z","caller":"traceutil/trace.go:171","msg":"trace[1940534720] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"196.638755ms","start":"2026-10-14T17:44:25.869715Z","end":"2026-10-14T17:44:26.066354Z","steps":["trace[1940534720] 'process raft request' (duration: 196.277307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:55.804181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.537509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:44:55.804327Z","caller":"traceutil/trace.go:171","msg":"trace[2123602407] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1176; }","duration":"106.766103ms","start":"2026-10-14T17:44:55.697536Z","end":"2026-10-14T17:44:55.804302Z","steps":["trace[2123602407] 'count revisions from in-memory index tree' (duration: 106.436637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:56.277777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.749725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:44:56.277839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.377596ms","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-10-14T17:44:56.27787Z","caller":"traceutil/trace.go:171","msg":"trace[586929489] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1177; }","duration":"327.857857ms","start":"2026-10-14T17:44:55.94999Z","end":"2026-10-14T17:44:56.277848Z","steps":["trace[586929489] 'count revisions from in-memory index tree' (duration: 327.676574ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:56.2779Z","caller":"traceutil/trace.go:171","msg":"trace[1117317545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1177; }","duration":"253.474367ms","start":"2026-10-14T17:44:56.02441Z","end":"2026-10-14T17:44:56.277885Z","steps":["trace[1117317545] 'range keys from in-memory index tree' (duration: 253.153301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:56.277926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:44:55.949943Z","time spent":"327.96761ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:45:10.509935Z","caller":"traceutil/trace.go:171","msg":"trace[542508877] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"107.132876ms","start":"2026-10-14T17:45:10.402774Z","end":"2026-10-14T17:45:10.509907Z","steps":["trace[542508877] 'process raft request' (duration: 106.964223ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:44.229173Z","caller":"traceutil/trace.go:171","msg":"trace[2092931575] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"128.9397ms","start":"2026-10-14T17:45:44.100204Z","end":"2026-10-14T17:45:44.229143Z","steps":["trace[2092931575] 'process raft request' (duration: 128.723215ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:44.355608Z","caller":"traceutil/trace.go:171","msg":"trace[219453920] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"118.733703ms","start":"2026-10-14T17:45:44.236822Z","end":"2026-10-14T17:45:44.355556Z","steps":["trace[219453920] 'process raft request' (duration: 59.747315ms)","trace[219453920] 'compare' (duration: 58.815904ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:59.024265Z","caller":"traceutil/trace.go:171","msg":"trace[285853082] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"186.145798ms","start":"2026-10-14T17:45:58.838094Z","end":"2026-10-14T17:45:59.02424Z","steps":["trace[285853082] 'read index received' (duration: 185.965464ms)","trace[285853082] 'applied index is now lower than readState.Index' (duration: 179.444µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:59.024397Z","caller":"traceutil/trace.go:171","msg":"trace[386298233] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"206.08615ms","start":"2026-10-14T17:45:58.818276Z","end":"2026-10-14T17:45:59.024362Z","steps":["trace[386298233] 'process raft request' (duration: 205.824204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:59.02452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.417144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T17:45:59.024605Z","caller":"traceutil/trace.go:171","msg":"trace[1238719440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1970; }","duration":"186.551757ms","start":"2026-10-14T17:45:58.838035Z","end":"2026-10-14T17:45:59.024587Z","steps":["trace[1238719440] 'agreement among raft nodes before linearized reading' (duration: 186.335672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:46:28.667202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.848445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:46:28.66731Z","caller":"traceutil/trace.go:171","msg":"trace[665634347] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"174.521178ms","start":"2026-10-14T17:46:28.492773Z","end":"2026-10-14T17:46:28.667294Z","steps":["trace[665634347] 'read index received' (duration: 54.360886ms)","trace[665634347] 'applied index is now lower than readState.Index' (duration: 120.158992ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:46:28.66734Z","caller":"traceutil/trace.go:171","msg":"trace[617213649] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"203.204579ms","start":"2026-10-14T17:46:28.464103Z","end":"2026-10-14T17:46:28.667307Z","steps":["trace[617213649] 'process raft request' (duration: 83.169119ms)","trace[617213649] 'compare' (duration: 119.747333ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:46:28.667436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.660331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-10-14T17:46:28.667469Z","caller":"traceutil/trace.go:171","msg":"trace[797865218] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2140; }","duration":"174.751983ms","start":"2026-10-14T17:46:28.492708Z","end":"2026-10-14T17:46:28.66746Z","steps":["trace[797865218] 'agreement among raft nodes before linearized reading' (duration: 174.62838ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:46:34.489255Z","caller":"traceutil/trace.go:171","msg":"trace[969173692] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"177.548144ms","start":"2026-10-14T17:46:34.31167Z","end":"2026-10-14T17:46:34.489218Z","steps":["trace[969173692] 'process raft request' (duration: 177.415672ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:46:34.570556Z","caller":"traceutil/trace.go:171","msg":"trace[308881914] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"108.086337ms","start":"2026-10-14T17:46:34.462445Z","end":"2026-10-14T17:46:34.570531Z","steps":["trace[308881914] 'read index received' (duration: 26.795889ms)","trace[308881914] 'applied index is now lower than readState.Index' (duration: 81.289288ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:46:34.570647Z","caller":"traceutil/trace.go:171","msg":"trace[1987346613] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"108.247731ms","start":"2026-10-14T17:46:34.46237Z","end":"2026-10-14T17:46:34.570618Z","steps":["trace[1987346613] 'process raft request' (duration: 108.061006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:46:34.570784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.26023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:46:34.570858Z","caller":"traceutil/trace.go:171","msg":"trace[1532334214] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2172; }","duration":"108.428284ms","start":"2026-10-14T17:46:34.462411Z","end":"2026-10-14T17:46:34.570839Z","steps":["trace[1532334214] 'agreement among raft nodes before linearized reading' (duration: 108.24488ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:32.4567Z","caller":"traceutil/trace.go:171","msg":"trace[158600116] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"109.413275ms","start":"2026-10-14T17:47:32.347268Z","end":"2026-10-14T17:47:32.456681Z","steps":["trace[158600116] 'process raft request' (duration: 108.916185ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:36.402515Z","caller":"traceutil/trace.go:171","msg":"trace[1766311333] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"301.108633ms","start":"2026-10-14T17:47:36.101369Z","end":"2026-10-14T17:47:36.402478Z","steps":["trace[1766311333] 'process raft request' (duration: 211.83504ms)","trace[1766311333] 'compare' (duration: 89.017468ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:36.402546Z","caller":"traceutil/trace.go:171","msg":"trace[697148688] linearizableReadLoop","detail":"{readStateIndex:2775; appliedIndex:2773; }","duration":"277.571163ms","start":"2026-10-14T17:47:36.124942Z","end":"2026-10-14T17:47:36.402513Z","steps":["trace[697148688] 'read index received' (duration: 188.270219ms)","trace[697148688] 'applied index is now lower than readState.Index' (duration: 89.299554ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:36.402691Z","caller":"traceutil/trace.go:171","msg":"trace[1173903163] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"293.285387ms","start":"2026-10-14T17:47:36.10939Z","end":"2026-10-14T17:47:36.402676Z","steps":["trace[1173903163] 'process raft request' (duration: 292.997891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:36.402686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:47:36.101356Z","time spent":"301.242895ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:47:36.402864Z","caller":"traceutil/trace.go:171","msg":"trace[1418776933] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"160.7661ms","start":"2026-10-14T17:47:36.242087Z","end":"2026-10-14T17:47:36.402853Z","steps":["trace[1418776933] 'process raft request' (duration: 160.373643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:36.403017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.057533ms","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-10-14T17:47:36.403055Z","caller":"traceutil/trace.go:171","msg":"trace[1121339318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2674; }","duration":"278.148134ms","start":"2026-10-14T17:47:36.124897Z","end":"2026-10-14T17:47:36.403045Z","steps":["trace[1121339318] 'agreement among raft nodes before linearized reading' (duration: 278.041352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:36.403436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.596268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T17:47:36.403475Z","caller":"traceutil/trace.go:171","msg":"trace[903115919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2674; }","duration":"151.68519ms","start":"2026-10-14T17:47:36.25178Z","end":"2026-10-14T17:47:36.403465Z","steps":["trace[903115919] 'agreement among raft nodes before linearized reading' (duration: 151.556517ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:08.676079Z","caller":"traceutil/trace.go:171","msg":"trace[986880048] linearizableReadLoop","detail":"{readStateIndex:2976; appliedIndex:2975; }","duration":"124.836992ms","start":"2026-10-14T17:48:08.551214Z","end":"2026-10-14T17:48:08.676051Z","steps":["trace[986880048] 'read index received' (duration: 124.651778ms)","trace[986880048] 'applied index is now lower than readState.Index' (duration: 180.354µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:08.676234Z","caller":"traceutil/trace.go:171","msg":"trace[1925448170] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"159.154001ms","start":"2026-10-14T17:48:08.517066Z","end":"2026-10-14T17:48:08.67622Z","steps":["trace[1925448170] 'process raft request' (duration: 158.751923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:08.676453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.065512ms","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-10-14T17:48:08.676532Z","caller":"traceutil/trace.go:171","msg":"trace[92631934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2867; }","duration":"118.211226ms","start":"2026-10-14T17:48:08.558304Z","end":"2026-10-14T17:48:08.676515Z","steps":["trace[92631934] 'agreement among raft nodes before linearized reading' (duration: 118.005931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:08.676734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.784642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:48:08.676768Z","caller":"traceutil/trace.go:171","msg":"trace[1280743507] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2867; }","duration":"106.821803ms","start":"2026-10-14T17:48:08.569934Z","end":"2026-10-14T17:48:08.676756Z","steps":["trace[1280743507] 'agreement among raft nodes before linearized reading' (duration: 106.768172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:08.67704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.808344ms","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-10-14T17:48:08.677134Z","caller":"traceutil/trace.go:171","msg":"trace[1481863575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2867; }","duration":"125.958597ms","start":"2026-10-14T17:48:08.551155Z","end":"2026-10-14T17:48:08.677114Z","steps":["trace[1481863575] 'agreement among raft nodes before linearized reading' (duration: 125.62012ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:08.817911Z","caller":"traceutil/trace.go:171","msg":"trace[1914265787] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"136.306176ms","start":"2026-10-14T17:48:08.681585Z","end":"2026-10-14T17:48:08.817891Z","steps":["trace[1914265787] 'process raft request' (duration: 136.237435ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:08.817974Z","caller":"traceutil/trace.go:171","msg":"trace[142613268] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"136.629743ms","start":"2026-10-14T17:48:08.681307Z","end":"2026-10-14T17:48:08.817936Z","steps":["trace[142613268] 'process raft request' (duration: 136.406888ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:14.206941Z","caller":"traceutil/trace.go:171","msg":"trace[96028116] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"263.467048ms","start":"2026-10-14T17:48:13.94344Z","end":"2026-10-14T17:48:14.206907Z","steps":["trace[96028116] 'process raft request' (duration: 263.384936ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:14.207261Z","caller":"traceutil/trace.go:171","msg":"trace[802149690] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"348.178792ms","start":"2026-10-14T17:48:13.859066Z","end":"2026-10-14T17:48:14.207245Z","steps":["trace[802149690] 'process raft request' (duration: 347.61704ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:14.207314Z","caller":"traceutil/trace.go:171","msg":"trace[1187247031] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"350.191188ms","start":"2026-10-14T17:48:13.857101Z","end":"2026-10-14T17:48:14.207292Z","steps":["trace[1187247031] 'process raft request' (duration: 254.232804ms)","trace[1187247031] 'compare' (duration: 95.113475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:14.207405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:13.857086Z","time spent":"350.270059ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:48:14.207401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:13.859047Z","time spent":"348.259325ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:26.103805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.830375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:51:26.103886Z","caller":"traceutil/trace.go:171","msg":"trace[1234304922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4111; }","duration":"108.955277ms","start":"2026-10-14T17:51:25.994914Z","end":"2026-10-14T17:51:26.103869Z","steps":["trace[1234304922] 'range keys from in-memory index tree' (duration: 108.750743ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:53.627827Z","caller":"traceutil/trace.go:171","msg":"trace[929173853] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"137.370323ms","start":"2026-10-14T17:52:53.490428Z","end":"2026-10-14T17:52:53.627798Z","steps":["trace[929173853] 'process raft request' (duration: 137.119957ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:58.238488Z","caller":"traceutil/trace.go:171","msg":"trace[1000542226] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"112.841976ms","start":"2026-10-14T17:52:58.125577Z","end":"2026-10-14T17:52:58.238419Z","steps":["trace[1000542226] 'process raft request' (duration: 112.56296ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.173176Z","caller":"traceutil/trace.go:171","msg":"trace[2829463] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"367.582825ms","start":"2026-10-14T17:53:17.805569Z","end":"2026-10-14T17:53:18.173152Z","steps":["trace[2829463] 'process raft request' (duration: 367.403051ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.173178Z","caller":"traceutil/trace.go:171","msg":"trace[1672052020] linearizableReadLoop","detail":"{readStateIndex:5929; appliedIndex:5928; }","duration":"318.128724ms","start":"2026-10-14T17:53:17.855004Z","end":"2026-10-14T17:53:18.173133Z","steps":["trace[1672052020] 'read index received' (duration: 318.107303ms)","trace[1672052020] 'applied index is now lower than readState.Index' (duration: 18.381µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:53:18.173395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:53:17.805535Z","time spent":"367.753598ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:53:18.173468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.447661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:53:18.173537Z","caller":"traceutil/trace.go:171","msg":"trace[1298973251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5719; }","duration":"318.579374ms","start":"2026-10-14T17:53:17.854941Z","end":"2026-10-14T17:53:18.17352Z","steps":["trace[1298973251] 'agreement among raft nodes before linearized reading' (duration: 318.327629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.173572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:53:17.854921Z","time spent":"318.644015ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T17:53:18.316221Z","caller":"traceutil/trace.go:171","msg":"trace[478164792] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"421.732498ms","start":"2026-10-14T17:53:17.894442Z","end":"2026-10-14T17:53:18.316174Z","steps":["trace[478164792] 'process raft request' (duration: 388.380449ms)","trace[478164792] 'compare' (duration: 33.191955ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:53:18.316412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:53:17.894424Z","time spent":"421.879041ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:53:18.316603Z","caller":"traceutil/trace.go:171","msg":"trace[25326249] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"167.517041ms","start":"2026-10-14T17:53:18.149067Z","end":"2026-10-14T17:53:18.316585Z","steps":["trace[25326249] 'process raft request' (duration: 167.066331ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.316645Z","caller":"traceutil/trace.go:171","msg":"trace[866533132] linearizableReadLoop","detail":"{readStateIndex:5931; appliedIndex:5929; }","duration":"143.169948ms","start":"2026-10-14T17:53:18.173364Z","end":"2026-10-14T17:53:18.316534Z","steps":["trace[866533132] 'read index received' (duration: 109.430831ms)","trace[866533132] 'applied index is now lower than readState.Index' (duration: 33.737797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:53:18.316674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.813707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:53:18.316752Z","caller":"traceutil/trace.go:171","msg":"trace[128392029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5721; }","duration":"167.93177ms","start":"2026-10-14T17:53:18.148801Z","end":"2026-10-14T17:53:18.316733Z","steps":["trace[128392029] 'agreement among raft nodes before linearized reading' (duration: 167.746686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.31682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.107921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:53:18.316873Z","caller":"traceutil/trace.go:171","msg":"trace[548396919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5721; }","duration":"322.197863ms","start":"2026-10-14T17:53:17.994659Z","end":"2026-10-14T17:53:18.316857Z","steps":["trace[548396919] 'agreement among raft nodes before linearized reading' (duration: 322.126911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.316901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:53:17.994641Z","time spent":"322.254163ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T17:53:18.316948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.117495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T17:53:18.316984Z","caller":"traceutil/trace.go:171","msg":"trace[250798065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5721; }","duration":"459.177556ms","start":"2026-10-14T17:53:17.857796Z","end":"2026-10-14T17:53:18.316974Z","steps":["trace[250798065] 'agreement among raft nodes before linearized reading' (duration: 459.050263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.317012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:53:17.857782Z","time spent":"459.223617ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T17:53:18.317377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.686253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T17:53:18.317432Z","caller":"traceutil/trace.go:171","msg":"trace[1668182076] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5721; }","duration":"182.798875ms","start":"2026-10-14T17:53:18.13462Z","end":"2026-10-14T17:53:18.317419Z","steps":["trace[1668182076] 'agreement among raft nodes before linearized reading' (duration: 182.154421ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.33815Z","caller":"traceutil/trace.go:171","msg":"trace[2004385761] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"158.943933ms","start":"2026-10-14T17:53:18.179183Z","end":"2026-10-14T17:53:18.338127Z","steps":["trace[2004385761] 'process raft request' (duration: 158.399781ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.482937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2918} {"level":"warn","ts":"2026-10-14T17:53:18.485509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.746857ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-10-14T17:53:18.48572Z","caller":"traceutil/trace.go:171","msg":"trace[228168026] compact","detail":"{revision:2918; response_revision:5723; }","duration":"167.786257ms","start":"2026-10-14T17:53:18.317916Z","end":"2026-10-14T17:53:18.485702Z","steps":["trace[228168026] 'process raft request' (duration: 45.245339ms)","trace[228168026] 'check and update compact revision' (duration: 119.436101ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:53:18.511824Z","caller":"traceutil/trace.go:171","msg":"trace[1548718037] linearizableReadLoop","detail":"{readStateIndex:5935; appliedIndex:5933; }","duration":"173.569754ms","start":"2026-10-14T17:53:18.338227Z","end":"2026-10-14T17:53:18.511797Z","steps":["trace[1548718037] 'read index received' (duration: 24.954085ms)","trace[1548718037] 'applied index is now lower than readState.Index' (duration: 148.614719ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:53:18.511873Z","caller":"traceutil/trace.go:171","msg":"trace[179709620] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"190.523384ms","start":"2026-10-14T17:53:18.321303Z","end":"2026-10-14T17:53:18.511826Z","steps":["trace[179709620] 'process raft request' (duration: 164.333552ms)","trace[179709620] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 25.946507ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:53:18.51208Z","caller":"traceutil/trace.go:171","msg":"trace[1502716371] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"169.28013ms","start":"2026-10-14T17:53:18.342777Z","end":"2026-10-14T17:53:18.512057Z","steps":["trace[1502716371] 'process raft request' (duration: 168.958963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.512111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.025206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-10-14T17:53:18.512111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.598997ms","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-10-14T17:53:18.512145Z","caller":"traceutil/trace.go:171","msg":"trace[1310483490] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5725; }","duration":"191.111647ms","start":"2026-10-14T17:53:18.321023Z","end":"2026-10-14T17:53:18.512135Z","steps":["trace[1310483490] 'agreement among raft nodes before linearized reading' (duration: 190.845311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.512328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.950699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:53:18.51237Z","caller":"traceutil/trace.go:171","msg":"trace[1523146189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5725; }","duration":"194.013841ms","start":"2026-10-14T17:53:18.318343Z","end":"2026-10-14T17:53:18.512357Z","steps":["trace[1523146189] 'agreement among raft nodes before linearized reading' (duration: 193.597282ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.512516Z","caller":"traceutil/trace.go:171","msg":"trace[1036354456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5725; }","duration":"153.72147ms","start":"2026-10-14T17:53:18.358425Z","end":"2026-10-14T17:53:18.512147Z","steps":["trace[1036354456] 'agreement among raft nodes before linearized reading' (duration: 153.566747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.512585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.66955ms","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-10-14T17:53:18.512616Z","caller":"traceutil/trace.go:171","msg":"trace[596001794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5725; }","duration":"148.72375ms","start":"2026-10-14T17:53:18.363883Z","end":"2026-10-14T17:53:18.512607Z","steps":["trace[596001794] 'agreement among raft nodes before linearized reading' (duration: 148.626828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:18.512706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.938559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T17:53:18.512783Z","caller":"traceutil/trace.go:171","msg":"trace[2140408393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5725; }","duration":"151.052732ms","start":"2026-10-14T17:53:18.361717Z","end":"2026-10-14T17:53:18.51277Z","steps":["trace[2140408393] 'agreement among raft nodes before linearized reading' (duration: 150.848847ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:18.561575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2918,"took":"77.088765ms","hash":3878402447,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T17:53:18.561661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3878402447,"revision":2918,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:53:24.981589Z","caller":"traceutil/trace.go:171","msg":"trace[696453007] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"103.054232ms","start":"2026-10-14T17:53:24.878484Z","end":"2026-10-14T17:53:24.981538Z","steps":["trace[696453007] 'process raft request' (duration: 102.862788ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:18.493756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5721} {"level":"info","ts":"2026-10-14T17:58:18.598327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5721,"took":"102.221343ms","hash":3884901432,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":26406912,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T17:58:18.598414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3884901432,"revision":5721,"compact-revision":2918} {"level":"info","ts":"2026-10-14T17:59:22.311749Z","caller":"traceutil/trace.go:171","msg":"trace[598765650] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"281.379992ms","start":"2026-10-14T17:59:22.030338Z","end":"2026-10-14T17:59:22.311718Z","steps":["trace[598765650] 'process raft request' (duration: 281.160287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:22.520765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.804976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:59:22.520976Z","caller":"traceutil/trace.go:171","msg":"trace[1097065538] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"482.81126ms","start":"2026-10-14T17:59:22.03812Z","end":"2026-10-14T17:59:22.520931Z","steps":["trace[1097065538] 'process raft request' (duration: 376.697127ms)","trace[1097065538] 'compare' (duration: 105.555441ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:59:22.521212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:59:22.038108Z","time spent":"482.996474ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:59:34.5203Z","caller":"traceutil/trace.go:171","msg":"trace[1151548040] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"142.981297ms","start":"2026-10-14T17:59:34.377284Z","end":"2026-10-14T17:59:34.520265Z","steps":["trace[1151548040] 'process raft request' (duration: 142.714541ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:34.960315Z","caller":"traceutil/trace.go:171","msg":"trace[1078069104] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"381.358869ms","start":"2026-10-14T17:59:34.578924Z","end":"2026-10-14T17:59:34.960283Z","steps":["trace[1078069104] 'read index received' (duration: 381.215686ms)","trace[1078069104] 'applied index is now lower than readState.Index' (duration: 141.613µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:59:34.960408Z","caller":"traceutil/trace.go:171","msg":"trace[1943715781] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"387.333742ms","start":"2026-10-14T17:59:34.573025Z","end":"2026-10-14T17:59:34.960359Z","steps":["trace[1943715781] 'process raft request' (duration: 387.067666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:34.960653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:59:34.572983Z","time spent":"387.514626ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:59:34.960724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.783559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:59:34.960837Z","caller":"traceutil/trace.go:171","msg":"trace[777382171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8609; }","duration":"381.948302ms","start":"2026-10-14T17:59:34.578869Z","end":"2026-10-14T17:59:34.960817Z","steps":["trace[777382171] 'agreement among raft nodes before linearized reading' (duration: 381.556344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:34.960918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:59:34.578847Z","time spent":"382.059765ms","remote":"127.0.0.1:48708","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-10-14T17:59:34.974896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.794544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T17:59:34.975006Z","caller":"traceutil/trace.go:171","msg":"trace[1437520308] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8610; }","duration":"340.980938ms","start":"2026-10-14T17:59:34.634004Z","end":"2026-10-14T17:59:34.974985Z","steps":["trace[1437520308] 'agreement among raft nodes before linearized reading' (duration: 340.680022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:34.975062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:59:34.633975Z","time spent":"341.071471ms","remote":"127.0.0.1:48616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-10-14T17:59:34.975068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.162253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:59:34.975092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.992638ms","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-10-14T17:59:34.975137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.661938ms","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-10-14T17:59:34.975164Z","caller":"traceutil/trace.go:171","msg":"trace[1392828030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8610; }","duration":"206.0838ms","start":"2026-10-14T17:59:34.769063Z","end":"2026-10-14T17:59:34.975147Z","steps":["trace[1392828030] 'agreement among raft nodes before linearized reading' (duration: 205.902136ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:34.9752Z","caller":"traceutil/trace.go:171","msg":"trace[1164254437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8610; }","duration":"218.789161ms","start":"2026-10-14T17:59:34.756396Z","end":"2026-10-14T17:59:34.975185Z","steps":["trace[1164254437] 'agreement among raft nodes before linearized reading' (duration: 218.518615ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:34.974902Z","caller":"traceutil/trace.go:171","msg":"trace[422699562] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"357.440572ms","start":"2026-10-14T17:59:34.61743Z","end":"2026-10-14T17:59:34.97487Z","steps":["trace[422699562] 'process raft request' (duration: 356.997582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:34.975033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.906435ms","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-10-14T17:59:34.975401Z","caller":"traceutil/trace.go:171","msg":"trace[46919719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8610; }","duration":"210.335064ms","start":"2026-10-14T17:59:34.76505Z","end":"2026-10-14T17:59:34.975385Z","steps":["trace[46919719] 'agreement among raft nodes before linearized reading' (duration: 209.850403ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:34.975117Z","caller":"traceutil/trace.go:171","msg":"trace[372514417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8610; }","duration":"168.268025ms","start":"2026-10-14T17:59:34.806839Z","end":"2026-10-14T17:59:34.975107Z","steps":["trace[372514417] 'agreement among raft nodes before linearized reading' (duration: 168.132932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:34.975497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:59:34.617396Z","time spent":"357.918082ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:59:34.974943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.510069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T17:59:34.975658Z","caller":"traceutil/trace.go:171","msg":"trace[438567393] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8610; }","duration":"197.242525ms","start":"2026-10-14T17:59:34.778398Z","end":"2026-10-14T17:59:34.975641Z","steps":["trace[438567393] 'agreement among raft nodes before linearized reading' (duration: 196.424697ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:11.325329Z","caller":"traceutil/trace.go:171","msg":"trace[1504331331] transaction","detail":"{read_only:false; response_revision:8855; number_of_response:1; }","duration":"135.013341ms","start":"2026-10-14T18:00:11.189727Z","end":"2026-10-14T18:00:11.32474Z","steps":["trace[1504331331] 'process raft request' (duration: 134.828007ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:11.336016Z","caller":"traceutil/trace.go:171","msg":"trace[819391780] linearizableReadLoop","detail":"{readStateIndex:9165; appliedIndex:9163; }","duration":"119.902277ms","start":"2026-10-14T18:00:11.216101Z","end":"2026-10-14T18:00:11.336003Z","steps":["trace[819391780] 'read index received' (duration: 108.455334ms)","trace[819391780] 'applied index is now lower than readState.Index' (duration: 11.445983ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:11.336077Z","caller":"traceutil/trace.go:171","msg":"trace[943616550] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"142.176709ms","start":"2026-10-14T18:00:11.193883Z","end":"2026-10-14T18:00:11.33606Z","steps":["trace[943616550] 'process raft request' (duration: 141.990535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:11.336205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.097212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T18:00:11.33623Z","caller":"traceutil/trace.go:171","msg":"trace[1499939934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8856; }","duration":"120.200754ms","start":"2026-10-14T18:00:11.216023Z","end":"2026-10-14T18:00:11.336224Z","steps":["trace[1499939934] 'agreement among raft nodes before linearized reading' (duration: 120.04811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:11.336312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.329437ms","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-10-14T18:00:11.336402Z","caller":"traceutil/trace.go:171","msg":"trace[1078490385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8856; }","duration":"101.47106ms","start":"2026-10-14T18:00:11.234912Z","end":"2026-10-14T18:00:11.336383Z","steps":["trace[1078490385] 'agreement among raft nodes before linearized reading' (duration: 101.197824ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:11.868778Z","caller":"traceutil/trace.go:171","msg":"trace[2128769041] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"235.95722ms","start":"2026-10-14T18:00:11.632783Z","end":"2026-10-14T18:00:11.868741Z","steps":["trace[2128769041] 'process raft request' (duration: 234.61524ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:18.431576Z","caller":"traceutil/trace.go:171","msg":"trace[1436277556] transaction","detail":"{read_only:false; response_revision:8933; number_of_response:1; }","duration":"177.326686ms","start":"2026-10-14T18:00:18.254219Z","end":"2026-10-14T18:00:18.431546Z","steps":["trace[1436277556] 'process raft request' (duration: 163.261275ms)","trace[1436277556] 'compare' (duration: 13.847466ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:18.43182Z","caller":"traceutil/trace.go:171","msg":"trace[25776825] linearizableReadLoop","detail":"{readStateIndex:9248; appliedIndex:9246; }","duration":"114.981299ms","start":"2026-10-14T18:00:18.316803Z","end":"2026-10-14T18:00:18.431785Z","steps":["trace[25776825] 'read index received' (duration: 100.581271ms)","trace[25776825] 'applied index is now lower than readState.Index' (duration: 14.397298ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:18.43199Z","caller":"traceutil/trace.go:171","msg":"trace[535046872] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8934; }","duration":"175.593076ms","start":"2026-10-14T18:00:18.256345Z","end":"2026-10-14T18:00:18.431938Z","steps":["trace[535046872] 'process raft request' (duration: 175.109016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:18.43214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.299036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5b9zp\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-14T18:00:18.432191Z","caller":"traceutil/trace.go:171","msg":"trace[1926529800] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5b9zp; range_end:; response_count:1; response_revision:8934; }","duration":"115.395317ms","start":"2026-10-14T18:00:18.316779Z","end":"2026-10-14T18:00:18.432174Z","steps":["trace[1926529800] 'agreement among raft nodes before linearized reading' (duration: 115.121061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:19.098097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:00:19.098414Z","caller":"traceutil/trace.go:171","msg":"trace[1810766890] linearizableReadLoop","detail":"{readStateIndex:9251; appliedIndex:9249; }","duration":"103.127917ms","start":"2026-10-14T18:00:18.995266Z","end":"2026-10-14T18:00:19.098394Z","steps":["trace[1810766890] 'read index received' (duration: 30.02µs)","trace[1810766890] 'applied index is now lower than readState.Index' (duration: 103.096807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:00:19.098533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.26164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:00:19.098563Z","caller":"traceutil/trace.go:171","msg":"trace[858203364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8937; }","duration":"103.318111ms","start":"2026-10-14T18:00:18.995232Z","end":"2026-10-14T18:00:19.09855Z","steps":["trace[858203364] 'agreement among raft nodes before linearized reading' (duration: 103.226069ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:19.098826Z","caller":"traceutil/trace.go:171","msg":"trace[1002140502] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"374.297364ms","start":"2026-10-14T18:00:18.724515Z","end":"2026-10-14T18:00:19.098812Z","steps":["trace[1002140502] 'process raft request' (duration: 373.707301ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:19.098778Z","caller":"traceutil/trace.go:171","msg":"trace[1397509351] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"380.541653ms","start":"2026-10-14T18:00:18.718205Z","end":"2026-10-14T18:00:19.098746Z","steps":["trace[1397509351] 'process raft request' (duration: 229.019247ms)","trace[1397509351] 'compare' (duration: 150.522423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:00:19.09889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:00:18.72449Z","time spent":"374.363715ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:00:19.099123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:00:18.718184Z","time spent":"380.732066ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:00:19.332391Z","caller":"traceutil/trace.go:171","msg":"trace[1135503213] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"101.177523ms","start":"2026-10-14T18:00:19.231186Z","end":"2026-10-14T18:00:19.332363Z","steps":["trace[1135503213] 'process raft request' (duration: 100.975579ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:34.903496Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ba.wal"} {"level":"warn","ts":"2026-10-14T18:01:40.019878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.116269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:01:40.020026Z","caller":"traceutil/trace.go:171","msg":"trace[694612531] linearizableReadLoop","detail":"{readStateIndex:9984; appliedIndex:9983; }","duration":"118.296429ms","start":"2026-10-14T18:01:39.901705Z","end":"2026-10-14T18:01:40.020002Z","steps":["trace[694612531] 'read index received' (duration: 72.052µs)","trace[694612531] 'applied index is now lower than readState.Index' (duration: 118.222557ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:01:40.020042Z","caller":"traceutil/trace.go:171","msg":"trace[867767243] transaction","detail":"{read_only:false; response_revision:9649; number_of_response:1; }","duration":"215.311718ms","start":"2026-10-14T18:01:39.804696Z","end":"2026-10-14T18:01:40.020008Z","steps":["trace[867767243] 'process raft request' (duration: 60.940934ms)","trace[867767243] 'compare' (duration: 153.942855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:01:40.020255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.537534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T18:01:40.020356Z","caller":"traceutil/trace.go:171","msg":"trace[430941663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9649; }","duration":"118.694027ms","start":"2026-10-14T18:01:39.901625Z","end":"2026-10-14T18:01:40.020319Z","steps":["trace[430941663] 'agreement among raft nodes before linearized reading' (duration: 118.494483ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:41.131326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b856fd30c5dc1b8","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:01:41.141849Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-10-14T18:01:41.141978Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-10-14T18:03:18.501212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7981} {"level":"info","ts":"2026-10-14T18:03:18.581834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7981,"took":"77.805476ms","hash":1060630837,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":28811264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T18:03:18.581927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060630837,"revision":7981,"compact-revision":5721} {"level":"warn","ts":"2026-10-14T18:03:59.858157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.075664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:03:59.858509Z","caller":"traceutil/trace.go:171","msg":"trace[2127781580] transaction","detail":"{read_only:false; response_revision:10804; number_of_response:1; }","duration":"162.572749ms","start":"2026-10-14T18:03:59.695898Z","end":"2026-10-14T18:03:59.85847Z","steps":["trace[2127781580] 'process raft request' (duration: 50.081355ms)","trace[2127781580] 'compare' (duration: 111.929261ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:00.837799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.065667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:00.838033Z","caller":"traceutil/trace.go:171","msg":"trace[1655431730] transaction","detail":"{read_only:false; response_revision:10809; number_of_response:1; }","duration":"170.440043ms","start":"2026-10-14T18:04:00.667556Z","end":"2026-10-14T18:04:00.837996Z","steps":["trace[1655431730] 'process raft request' (duration: 46.971817ms)","trace[1655431730] 'compare' (duration: 122.894443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:01.163419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.078109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:01.163612Z","caller":"traceutil/trace.go:171","msg":"trace[814797772] linearizableReadLoop","detail":"{readStateIndex:11183; appliedIndex:11182; }","duration":"218.294709ms","start":"2026-10-14T18:04:00.945295Z","end":"2026-10-14T18:04:01.16359Z","steps":["trace[814797772] 'read index received' (duration: 16.936384ms)","trace[814797772] 'applied index is now lower than readState.Index' (duration: 201.356575ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:01.16395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.272445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:01.163965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.638226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T18:04:01.164052Z","caller":"traceutil/trace.go:171","msg":"trace[1893249123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10810; }","duration":"168.479829ms","start":"2026-10-14T18:04:00.99555Z","end":"2026-10-14T18:04:01.164029Z","steps":["trace[1893249123] 'agreement among raft nodes before linearized reading' (duration: 168.257034ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:01.164085Z","caller":"traceutil/trace.go:171","msg":"trace[26741518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10810; }","duration":"218.824901ms","start":"2026-10-14T18:04:00.945231Z","end":"2026-10-14T18:04:01.164056Z","steps":["trace[26741518] 'agreement among raft nodes before linearized reading' (duration: 218.462963ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:01.164096Z","caller":"traceutil/trace.go:171","msg":"trace[1365386540] transaction","detail":"{read_only:false; response_revision:10810; number_of_response:1; }","duration":"320.836043ms","start":"2026-10-14T18:04:00.842818Z","end":"2026-10-14T18:04:01.163654Z","steps":["trace[1365386540] 'process raft request' (duration: 119.406766ms)","trace[1365386540] 'compare' (duration: 200.902975ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:01.163959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.711374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T18:04:01.164136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.753246ms","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-10-14T18:04:01.164235Z","caller":"traceutil/trace.go:171","msg":"trace[833889330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10810; }","duration":"193.078912ms","start":"2026-10-14T18:04:00.971139Z","end":"2026-10-14T18:04:01.164218Z","steps":["trace[833889330] 'agreement among raft nodes before linearized reading' (duration: 192.604302ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:01.164276Z","caller":"traceutil/trace.go:171","msg":"trace[1008905497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10810; }","duration":"178.970261ms","start":"2026-10-14T18:04:00.985277Z","end":"2026-10-14T18:04:01.164248Z","steps":["trace[1008905497] 'agreement among raft nodes before linearized reading' (duration: 178.569402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:01.164444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:00.842786Z","time spent":"321.536828ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:01.790066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.958136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:01.790229Z","caller":"traceutil/trace.go:171","msg":"trace[1190640206] transaction","detail":"{read_only:false; response_revision:10811; number_of_response:1; }","duration":"620.710102ms","start":"2026-10-14T18:04:01.16949Z","end":"2026-10-14T18:04:01.7902Z","steps":["trace[1190640206] 'process raft request' (duration: 231.48498ms)","trace[1190640206] 'compare' (duration: 388.578548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:01.790294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.169458Z","time spent":"620.805694ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:02.409232Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008142665s","expected-duration":"1s"} {"level":"warn","ts":"2026-10-14T18:04:02.763309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.689697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:02.763843Z","caller":"traceutil/trace.go:171","msg":"trace[534188156] linearizableReadLoop","detail":"{readStateIndex:11188; appliedIndex:11183; }","duration":"1.510738018s","start":"2026-10-14T18:04:01.253079Z","end":"2026-10-14T18:04:02.763817Z","steps":["trace[534188156] 'read index received' (duration: 147.979446ms)","trace[534188156] 'applied index is now lower than readState.Index' (duration: 1.362757002s)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:02.764259Z","caller":"traceutil/trace.go:171","msg":"trace[343534429] transaction","detail":"{read_only:false; response_revision:10814; number_of_response:1; }","duration":"1.594307503s","start":"2026-10-14T18:04:01.169927Z","end":"2026-10-14T18:04:02.764234Z","steps":["trace[343534429] 'process raft request' (duration: 1.59367699s)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:02.764302Z","caller":"traceutil/trace.go:171","msg":"trace[817855135] transaction","detail":"{read_only:false; response_revision:10813; number_of_response:1; }","duration":"1.594265923s","start":"2026-10-14T18:04:01.169673Z","end":"2026-10-14T18:04:02.763939Z","steps":["trace[817855135] 'process raft request' (duration: 1.593760522s)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:02.764282Z","caller":"traceutil/trace.go:171","msg":"trace[1375958707] transaction","detail":"{read_only:false; response_revision:10812; number_of_response:1; }","duration":"1.594660751s","start":"2026-10-14T18:04:01.16957Z","end":"2026-10-14T18:04:02.764231Z","steps":["trace[1375958707] 'process raft request' (duration: 1.239925172s)","trace[1375958707] 'compare' (duration: 353.41195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:02.764468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511389153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-js9wk\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-14T18:04:02.764521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.169666Z","time spent":"1.594730293s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:02.764531Z","caller":"traceutil/trace.go:171","msg":"trace[1568523475] range","detail":"{range_begin:/registry/pods/openstack/keepalived-js9wk; range_end:; response_count:1; response_revision:10815; }","duration":"1.511497716s","start":"2026-10-14T18:04:01.25302Z","end":"2026-10-14T18:04:02.764517Z","steps":["trace[1568523475] 'agreement among raft nodes before linearized reading' (duration: 1.510927603s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.764536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.169536Z","time spent":"1.594927838s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:02.764572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.253006Z","time spent":"1.511557617s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-js9wk\" "} {"level":"warn","ts":"2026-10-14T18:04:02.764477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.169915Z","time spent":"1.594442016s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:02.7651Z","caller":"traceutil/trace.go:171","msg":"trace[609352233] transaction","detail":"{read_only:false; response_revision:10815; number_of_response:1; }","duration":"1.594432186s","start":"2026-10-14T18:04:01.170068Z","end":"2026-10-14T18:04:02.7645Z","steps":["trace[609352233] 'process raft request' (duration: 1.593602558s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.765234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.170057Z","time spent":"1.595111431s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:02.830278Z","caller":"traceutil/trace.go:171","msg":"trace[1705251270] transaction","detail":"{read_only:false; response_revision:10816; number_of_response:1; }","duration":"742.51373ms","start":"2026-10-14T18:04:02.08774Z","end":"2026-10-14T18:04:02.830253Z","steps":["trace[1705251270] 'process raft request' (duration: 742.382887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.830431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.087705Z","time spent":"742.640983ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:03.570619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.629196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:03.570815Z","caller":"traceutil/trace.go:171","msg":"trace[1926984226] linearizableReadLoop","detail":"{readStateIndex:11190; appliedIndex:11189; }","duration":"806.879622ms","start":"2026-10-14T18:04:02.763906Z","end":"2026-10-14T18:04:03.570785Z","steps":["trace[1926984226] 'read index received' (duration: 66.410616ms)","trace[1926984226] 'applied index is now lower than readState.Index' (duration: 740.466676ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:03.570822Z","caller":"traceutil/trace.go:171","msg":"trace[715910079] transaction","detail":"{read_only:false; response_revision:10817; number_of_response:1; }","duration":"1.047559205s","start":"2026-10-14T18:04:02.52322Z","end":"2026-10-14T18:04:03.570779Z","steps":["trace[715910079] 'process raft request' (duration: 688.629752ms)","trace[715910079] 'compare' (duration: 357.694586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:03.570971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.523193Z","time spent":"1.047692997s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:03.571102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.57562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-14T18:04:03.571164Z","caller":"traceutil/trace.go:171","msg":"trace[174374366] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:10817; }","duration":"861.641511ms","start":"2026-10-14T18:04:02.709502Z","end":"2026-10-14T18:04:03.571144Z","steps":["trace[174374366] 'agreement among raft nodes before linearized reading' (duration: 861.407806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:03.571215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.709428Z","time spent":"861.772773ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:04:03.571215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942350747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T18:04:03.571316Z","caller":"traceutil/trace.go:171","msg":"trace[1640285104] range","detail":"{range_begin:/registry/masterleases/162.253.55.169; range_end:; response_count:1; response_revision:10817; }","duration":"1.9425033s","start":"2026-10-14T18:04:01.628787Z","end":"2026-10-14T18:04:03.57129Z","steps":["trace[1640285104] 'agreement among raft nodes before linearized reading' (duration: 1.942262135s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:03.571354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.628744Z","time spent":"1.942601203s","remote":"127.0.0.1:48490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.169\" "} {"level":"warn","ts":"2026-10-14T18:04:03.571546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.054169885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:03.571587Z","caller":"traceutil/trace.go:171","msg":"trace[947581853] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:0; response_revision:10817; }","duration":"2.054303728s","start":"2026-10-14T18:04:01.517271Z","end":"2026-10-14T18:04:03.571575Z","steps":["trace[947581853] 'agreement among raft nodes before linearized reading' (duration: 2.054199605s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:03.571615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.517237Z","time spent":"2.054370389s","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-10-14T18:04:04.071652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13959084322006281619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-14T18:04:04.425676Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213741124s","expected-duration":"1s"} {"level":"info","ts":"2026-10-14T18:04:04.523959Z","caller":"traceutil/trace.go:171","msg":"trace[794980148] transaction","detail":"{read_only:false; response_revision:10819; number_of_response:1; }","duration":"1.3576419s","start":"2026-10-14T18:04:03.166279Z","end":"2026-10-14T18:04:04.523921Z","steps":["trace[794980148] 'process raft request' (duration: 1.357472986s)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:04.524014Z","caller":"traceutil/trace.go:171","msg":"trace[1096589762] transaction","detail":"{read_only:false; response_revision:10818; number_of_response:1; }","duration":"1.680575139s","start":"2026-10-14T18:04:02.843384Z","end":"2026-10-14T18:04:04.523959Z","steps":["trace[1096589762] 'process raft request' (duration: 1.582678917s)","trace[1096589762] 'compare' (duration: 96.998452ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:04.524158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.166242Z","time spent":"1.357795803s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:04.524227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.843365Z","time spent":"1.680736563s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:04.996738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999691553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-10-14T18:04:04.996877Z","caller":"traceutil/trace.go:171","msg":"trace[160274974] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999909997s","start":"2026-10-14T18:04:02.996938Z","end":"2026-10-14T18:04:04.996848Z","steps":["trace[160274974] 'agreement among raft nodes before linearized reading' (duration: 1.999740894s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:04.996946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.996901Z","time spent":"2.00003255s","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/10/14 18:04:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-10-14T18:04:05.265868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.581601ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-14T18:04:05.266295Z","caller":"traceutil/trace.go:171","msg":"trace[1965375235] transaction","detail":"{read_only:false; response_revision:10821; number_of_response:1; }","duration":"430.79255ms","start":"2026-10-14T18:04:04.83548Z","end":"2026-10-14T18:04:05.266273Z","steps":["trace[1965375235] 'process raft request' (duration: 430.743979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.266462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.835451Z","time spent":"430.925003ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.266441Z","caller":"traceutil/trace.go:171","msg":"trace[669688010] transaction","detail":"{read_only:false; response_revision:10820; number_of_response:1; }","duration":"1.689063316s","start":"2026-10-14T18:04:03.577338Z","end":"2026-10-14T18:04:05.266402Z","steps":["trace[669688010] 'process raft request' (duration: 1.688741179s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.266648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097588465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T18:04:05.266724Z","caller":"traceutil/trace.go:171","msg":"trace[2062778859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10821; }","duration":"2.097720727s","start":"2026-10-14T18:04:03.168981Z","end":"2026-10-14T18:04:05.266702Z","steps":["trace[2062778859] 'agreement among raft nodes before linearized reading' (duration: 2.097457901s)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:05.266458Z","caller":"traceutil/trace.go:171","msg":"trace[1395050973] linearizableReadLoop","detail":"{readStateIndex:11194; appliedIndex:11190; }","duration":"1.69556003s","start":"2026-10-14T18:04:03.570861Z","end":"2026-10-14T18:04:05.266421Z","steps":["trace[1395050973] 'read index received' (duration: 855.228539ms)","trace[1395050973] 'applied index is now lower than readState.Index' (duration: 840.328121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:05.266794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.168964Z","time spent":"2.09782143s","remote":"127.0.0.1:48708","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-10-14T18:04:05.266462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.572648Z","time spent":"1.693802811s","remote":"127.0.0.1:48490","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-14T18:04:05.267007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.263265ms","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-10-14T18:04:05.267045Z","caller":"traceutil/trace.go:171","msg":"trace[230923713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10821; }","duration":"496.310496ms","start":"2026-10-14T18:04:04.770722Z","end":"2026-10-14T18:04:05.267033Z","steps":["trace[230923713] 'agreement among raft nodes before linearized reading' (duration: 496.216824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.26706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.518162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T18:04:05.267069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.770716Z","time spent":"496.347387ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T18:04:05.267082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.85025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T18:04:05.267097Z","caller":"traceutil/trace.go:171","msg":"trace[715178539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:10821; }","duration":"598.554873ms","start":"2026-10-14T18:04:04.668526Z","end":"2026-10-14T18:04:05.267081Z","steps":["trace[715178539] 'agreement among raft nodes before linearized reading' (duration: 598.498552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470607034s","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-10-14T18:04:05.267141Z","caller":"traceutil/trace.go:171","msg":"trace[139498612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10821; }","duration":"1.470777028s","start":"2026-10-14T18:04:03.796356Z","end":"2026-10-14T18:04:05.267133Z","steps":["trace[139498612] 'agreement among raft nodes before linearized reading' (duration: 1.470517963s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.668474Z","time spent":"598.665505ms","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:04:05.267146Z","caller":"traceutil/trace.go:171","msg":"trace[1335807084] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10821; }","duration":"497.955132ms","start":"2026-10-14T18:04:04.769164Z","end":"2026-10-14T18:04:05.26712Z","steps":["trace[1335807084] 'agreement among raft nodes before linearized reading' (duration: 497.726427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.796279Z","time spent":"1.47087944s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T18:04:05.267173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.496747637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:40 size:368111"} {"level":"warn","ts":"2026-10-14T18:04:05.267195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.769146Z","time spent":"498.039284ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T18:04:05.267222Z","caller":"traceutil/trace.go:171","msg":"trace[2050689826] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:40; response_revision:10821; }","duration":"2.496869799s","start":"2026-10-14T18:04:02.77034Z","end":"2026-10-14T18:04:05.26721Z","steps":["trace[2050689826] 'agreement among raft nodes before linearized reading' (duration: 2.496185094s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.728355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:05.26726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:02.770313Z","time spent":"2.49693597s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":40,"response size":368134,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-14T18:04:05.267299Z","caller":"traceutil/trace.go:171","msg":"trace[1798271258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10821; }","duration":"269.823407ms","start":"2026-10-14T18:04:04.997457Z","end":"2026-10-14T18:04:05.26728Z","steps":["trace[1798271258] 'agreement among raft nodes before linearized reading' (duration: 269.611062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.266545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738820794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:05.267508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.245932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:05.267544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.725757ms","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-10-14T18:04:05.267572Z","caller":"traceutil/trace.go:171","msg":"trace[1782017418] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:10821; }","duration":"654.342455ms","start":"2026-10-14T18:04:04.613213Z","end":"2026-10-14T18:04:05.267556Z","steps":["trace[1782017418] 'agreement among raft nodes before linearized reading' (duration: 654.261773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.613184Z","time spent":"654.429047ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:04:05.267567Z","caller":"traceutil/trace.go:171","msg":"trace[860108113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:10821; }","duration":"1.739903449s","start":"2026-10-14T18:04:03.527604Z","end":"2026-10-14T18:04:05.267507Z","steps":["trace[860108113] 'agreement among raft nodes before linearized reading' (duration: 1.738825365s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.347281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-14T18:04:05.2677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.577303Z","time spent":"1.689427944s","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.267724Z","caller":"traceutil/trace.go:171","msg":"trace[1103294882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10821; }","duration":"498.473574ms","start":"2026-10-14T18:04:04.769231Z","end":"2026-10-14T18:04:05.267705Z","steps":["trace[1103294882] 'agreement among raft nodes before linearized reading' (duration: 498.168997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.26772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.527576Z","time spent":"1.740123084s","remote":"127.0.0.1:49388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:04:05.267749Z","caller":"traceutil/trace.go:171","msg":"trace[148374674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10821; }","duration":"497.83632ms","start":"2026-10-14T18:04:04.769746Z","end":"2026-10-14T18:04:05.267582Z","steps":["trace[148374674] 'agreement among raft nodes before linearized reading' (duration: 497.623386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.769197Z","time spent":"498.552466ms","remote":"127.0.0.1:48708","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-10-14T18:04:05.267446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232570459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:05.267915Z","caller":"traceutil/trace.go:171","msg":"trace[602037369] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:10821; }","duration":"1.233015979s","start":"2026-10-14T18:04:04.034862Z","end":"2026-10-14T18:04:05.267878Z","steps":["trace[602037369] 'agreement among raft nodes before linearized reading' (duration: 1.232543268s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.433584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:05.267983Z","caller":"traceutil/trace.go:171","msg":"trace[1003219892] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:10821; }","duration":"962.075108ms","start":"2026-10-14T18:04:04.305892Z","end":"2026-10-14T18:04:05.267967Z","steps":["trace[1003219892] 'agreement among raft nodes before linearized reading' (duration: 961.434344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.267819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.769738Z","time spent":"498.067935ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T18:04:05.268042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.305862Z","time spent":"962.16416ms","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:04:05.267807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307703078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:05.268174Z","caller":"traceutil/trace.go:171","msg":"trace[944490884] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:10821; }","duration":"1.308121947s","start":"2026-10-14T18:04:03.960036Z","end":"2026-10-14T18:04:05.268158Z","steps":["trace[944490884] 'agreement among raft nodes before linearized reading' (duration: 1.307435482s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.268225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:03.960008Z","time spent":"1.308200989s","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:04:05.267987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:04.034577Z","time spent":"1.233389018s","remote":"127.0.0.1:38160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:04:05.507705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.305041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:05.507884Z","caller":"traceutil/trace.go:171","msg":"trace[1718560952] transaction","detail":"{read_only:false; response_revision:10822; number_of_response:1; }","duration":"235.672042ms","start":"2026-10-14T18:04:05.272184Z","end":"2026-10-14T18:04:05.507856Z","steps":["trace[1718560952] 'process raft request' (duration: 117.105245ms)","trace[1718560952] 'compare' (duration: 118.167549ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:05.788024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.445765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:05.788399Z","caller":"traceutil/trace.go:171","msg":"trace[1009201372] transaction","detail":"{read_only:false; response_revision:10825; number_of_response:1; }","duration":"513.821753ms","start":"2026-10-14T18:04:05.274556Z","end":"2026-10-14T18:04:05.788378Z","steps":["trace[1009201372] 'process raft request' (duration: 513.726591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.78849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.274542Z","time spent":"513.910025ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.788326Z","caller":"traceutil/trace.go:171","msg":"trace[98940079] transaction","detail":"{read_only:false; response_revision:10823; number_of_response:1; }","duration":"515.04658ms","start":"2026-10-14T18:04:05.273229Z","end":"2026-10-14T18:04:05.788276Z","steps":["trace[98940079] 'process raft request' (duration: 367.217076ms)","trace[98940079] 'compare' (duration: 146.975495ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:05.788495Z","caller":"traceutil/trace.go:171","msg":"trace[2013717267] transaction","detail":"{read_only:false; response_revision:10824; number_of_response:1; }","duration":"514.237922ms","start":"2026-10-14T18:04:05.274189Z","end":"2026-10-14T18:04:05.788427Z","steps":["trace[2013717267] 'process raft request' (duration: 513.975926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.78866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.273203Z","time spent":"515.346767ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:05.788715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.274162Z","time spent":"514.433727ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.788837Z","caller":"traceutil/trace.go:171","msg":"trace[1963775897] transaction","detail":"{read_only:false; response_revision:10826; number_of_response:1; }","duration":"513.986826ms","start":"2026-10-14T18:04:05.274811Z","end":"2026-10-14T18:04:05.788798Z","steps":["trace[1963775897] 'process raft request' (duration: 513.520796ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:05.788923Z","caller":"traceutil/trace.go:171","msg":"trace[1233357406] transaction","detail":"{read_only:false; response_revision:10827; number_of_response:1; }","duration":"513.964216ms","start":"2026-10-14T18:04:05.274945Z","end":"2026-10-14T18:04:05.788909Z","steps":["trace[1233357406] 'process raft request' (duration: 513.68652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.788982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.274916Z","time spent":"514.034468ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:05.789022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.274798Z","time spent":"514.12512ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.789079Z","caller":"traceutil/trace.go:171","msg":"trace[102210796] transaction","detail":"{read_only:false; response_revision:10828; number_of_response:1; }","duration":"513.856934ms","start":"2026-10-14T18:04:05.275196Z","end":"2026-10-14T18:04:05.789053Z","steps":["trace[102210796] 'process raft request' (duration: 513.642259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:05.789082Z","caller":"traceutil/trace.go:171","msg":"trace[659172817] linearizableReadLoop","detail":"{readStateIndex:11203; appliedIndex:11195; }","duration":"512.669607ms","start":"2026-10-14T18:04:05.276323Z","end":"2026-10-14T18:04:05.788993Z","steps":["trace[659172817] 'read index received' (duration: 113.011825ms)","trace[659172817] 'applied index is now lower than readState.Index' (duration: 399.656342ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:05.789155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.275174Z","time spent":"513.943765ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T18:04:05.789139Z","caller":"traceutil/trace.go:171","msg":"trace[1372902211] transaction","detail":"{read_only:false; response_revision:10829; number_of_response:1; }","duration":"512.872862ms","start":"2026-10-14T18:04:05.276214Z","end":"2026-10-14T18:04:05.789087Z","steps":["trace[1372902211] 'process raft request' (duration: 512.708258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.789218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.902612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:05.789254Z","caller":"traceutil/trace.go:171","msg":"trace[1431238346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10829; }","duration":"512.957464ms","start":"2026-10-14T18:04:05.276288Z","end":"2026-10-14T18:04:05.789245Z","steps":["trace[1431238346] 'agreement among raft nodes before linearized reading' (duration: 512.884982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.789292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.276277Z","time spent":"513.008275ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:04:05.789369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.276199Z","time spent":"513.045336ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:05.865419Z","caller":"traceutil/trace.go:171","msg":"trace[900104581] transaction","detail":"{read_only:false; response_revision:10830; number_of_response:1; }","duration":"286.75893ms","start":"2026-10-14T18:04:05.578642Z","end":"2026-10-14T18:04:05.865401Z","steps":["trace[900104581] 'process raft request' (duration: 286.645837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.865669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.12051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-10-14T18:04:05.865685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.007276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:05.865711Z","caller":"traceutil/trace.go:171","msg":"trace[681359869] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:10830; }","duration":"355.191071ms","start":"2026-10-14T18:04:05.510509Z","end":"2026-10-14T18:04:05.8657Z","steps":["trace[681359869] 'agreement among raft nodes before linearized reading' (duration: 355.034607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.865741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.189907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-14T18:04:05.865775Z","caller":"traceutil/trace.go:171","msg":"trace[446703030] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:0; response_revision:10830; }","duration":"287.089637ms","start":"2026-10-14T18:04:05.578645Z","end":"2026-10-14T18:04:05.865735Z","steps":["trace[446703030] 'agreement among raft nodes before linearized reading' (duration: 286.956294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.865751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.510498Z","time spent":"355.245652ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-14T18:04:05.86581Z","caller":"traceutil/trace.go:171","msg":"trace[1120490942] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:10830; }","duration":"387.28678ms","start":"2026-10-14T18:04:05.478509Z","end":"2026-10-14T18:04:05.865796Z","steps":["trace[1120490942] 'agreement among raft nodes before linearized reading' (duration: 387.110216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.865851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.478493Z","time spent":"387.351121ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-14T18:04:05.865569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.821641ms","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":"warn","ts":"2026-10-14T18:04:05.866046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.515241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T18:04:05.865989Z","caller":"traceutil/trace.go:171","msg":"trace[101184248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:10830; }","duration":"528.286372ms","start":"2026-10-14T18:04:05.337688Z","end":"2026-10-14T18:04:05.865975Z","steps":["trace[101184248] 'agreement among raft nodes before linearized reading' (duration: 527.818301ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:05.866164Z","caller":"traceutil/trace.go:171","msg":"trace[564217417] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10830; }","duration":"400.658284ms","start":"2026-10-14T18:04:05.465475Z","end":"2026-10-14T18:04:05.866134Z","steps":["trace[564217417] 'agreement among raft nodes before linearized reading' (duration: 400.233715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:05.866215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.337676Z","time spent":"528.526328ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:04:05.866225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.465464Z","time spent":"400.749827ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T18:04:06.02858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.897393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:06.028881Z","caller":"traceutil/trace.go:171","msg":"trace[384754546] transaction","detail":"{read_only:false; response_revision:10832; number_of_response:1; }","duration":"157.779123ms","start":"2026-10-14T18:04:05.871075Z","end":"2026-10-14T18:04:06.028854Z","steps":["trace[384754546] 'process raft request' (duration: 157.649381ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:06.029007Z","caller":"traceutil/trace.go:171","msg":"trace[1798114036] transaction","detail":"{read_only:false; response_revision:10831; number_of_response:1; }","duration":"234.111418ms","start":"2026-10-14T18:04:05.79486Z","end":"2026-10-14T18:04:06.028972Z","steps":["trace[1798114036] 'process raft request' (duration: 120.706254ms)","trace[1798114036] 'compare' (duration: 112.616907ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:06.029045Z","caller":"traceutil/trace.go:171","msg":"trace[710594160] linearizableReadLoop","detail":"{readStateIndex:11205; appliedIndex:11204; }","duration":"160.705758ms","start":"2026-10-14T18:04:05.868302Z","end":"2026-10-14T18:04:06.029008Z","steps":["trace[710594160] 'read index received' (duration: 47.270293ms)","trace[710594160] 'applied index is now lower than readState.Index' (duration: 113.432165ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:06.029424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.088886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T18:04:06.02947Z","caller":"traceutil/trace.go:171","msg":"trace[243644665] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:10832; }","duration":"161.187839ms","start":"2026-10-14T18:04:05.868271Z","end":"2026-10-14T18:04:06.029459Z","steps":["trace[243644665] 'agreement among raft nodes before linearized reading' (duration: 160.911023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:06.029588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.125577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T18:04:06.029696Z","caller":"traceutil/trace.go:171","msg":"trace[857139694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10832; }","duration":"156.265309ms","start":"2026-10-14T18:04:05.873403Z","end":"2026-10-14T18:04:06.029668Z","steps":["trace[857139694] 'agreement among raft nodes before linearized reading' (duration: 155.891971ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:06.814069Z","caller":"traceutil/trace.go:171","msg":"trace[135533072] transaction","detail":"{read_only:false; response_revision:10836; number_of_response:1; }","duration":"134.968219ms","start":"2026-10-14T18:04:06.678778Z","end":"2026-10-14T18:04:06.813747Z","steps":["trace[135533072] 'process raft request' (duration: 92.514042ms)","trace[135533072] 'compare' (duration: 42.268863ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:08.02241Z","caller":"traceutil/trace.go:171","msg":"trace[628330300] transaction","detail":"{read_only:false; response_revision:10842; number_of_response:1; }","duration":"215.687092ms","start":"2026-10-14T18:04:07.806695Z","end":"2026-10-14T18:04:08.022383Z","steps":["trace[628330300] 'process raft request' (duration: 215.501137ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:08.145952Z","caller":"traceutil/trace.go:171","msg":"trace[1586757280] linearizableReadLoop","detail":"{readStateIndex:11224; appliedIndex:11217; }","duration":"273.744693ms","start":"2026-10-14T18:04:07.872174Z","end":"2026-10-14T18:04:08.145918Z","steps":["trace[1586757280] 'read index received' (duration: 150.269037ms)","trace[1586757280] 'applied index is now lower than readState.Index' (duration: 123.474406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:08.146147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.938358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:08.146186Z","caller":"traceutil/trace.go:171","msg":"trace[394093794] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:0; response_revision:10850; }","duration":"274.046409ms","start":"2026-10-14T18:04:07.872129Z","end":"2026-10-14T18:04:08.146175Z","steps":["trace[394093794] 'agreement among raft nodes before linearized reading' (duration: 273.911756ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:08.146625Z","caller":"traceutil/trace.go:171","msg":"trace[1239572239] transaction","detail":"{read_only:false; response_revision:10843; number_of_response:1; }","duration":"337.527031ms","start":"2026-10-14T18:04:07.809084Z","end":"2026-10-14T18:04:08.146611Z","steps":["trace[1239572239] 'process raft request' (duration: 336.297814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.146739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.809055Z","time spent":"337.607373ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:08.146946Z","caller":"traceutil/trace.go:171","msg":"trace[463752373] transaction","detail":"{read_only:false; response_revision:10844; number_of_response:1; }","duration":"337.809507ms","start":"2026-10-14T18:04:07.809122Z","end":"2026-10-14T18:04:08.146932Z","steps":["trace[463752373] 'process raft request' (duration: 336.460197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.147011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.809105Z","time spent":"337.870689ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:08.147163Z","caller":"traceutil/trace.go:171","msg":"trace[259286164] transaction","detail":"{read_only:false; response_revision:10849; number_of_response:1; }","duration":"276.295019ms","start":"2026-10-14T18:04:07.870856Z","end":"2026-10-14T18:04:08.147151Z","steps":["trace[259286164] 'process raft request' (duration: 274.98549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:08.147157Z","caller":"traceutil/trace.go:171","msg":"trace[932874180] transaction","detail":"{read_only:false; response_revision:10848; number_of_response:1; }","duration":"323.923421ms","start":"2026-10-14T18:04:07.823185Z","end":"2026-10-14T18:04:08.147108Z","steps":["trace[932874180] 'process raft request' (duration: 322.610912ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:08.147301Z","caller":"traceutil/trace.go:171","msg":"trace[917460690] transaction","detail":"{read_only:false; response_revision:10850; number_of_response:1; }","duration":"239.287812ms","start":"2026-10-14T18:04:07.908001Z","end":"2026-10-14T18:04:08.147289Z","steps":["trace[917460690] 'process raft request' (duration: 237.877651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.147365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.823165Z","time spent":"324.083764ms","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:08.147659Z","caller":"traceutil/trace.go:171","msg":"trace[2085545666] transaction","detail":"{read_only:false; response_revision:10845; number_of_response:1; }","duration":"338.461622ms","start":"2026-10-14T18:04:07.809184Z","end":"2026-10-14T18:04:08.147645Z","steps":["trace[2085545666] 'process raft request' (duration: 336.458778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.147726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.809175Z","time spent":"338.517383ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:08.147895Z","caller":"traceutil/trace.go:171","msg":"trace[1278345330] transaction","detail":"{read_only:false; response_revision:10846; number_of_response:1; }","duration":"338.633925ms","start":"2026-10-14T18:04:07.809249Z","end":"2026-10-14T18:04:08.147883Z","steps":["trace[1278345330] 'process raft request' (duration: 336.435267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.147963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.809241Z","time spent":"338.688237ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:08.148066Z","caller":"traceutil/trace.go:171","msg":"trace[1968304271] transaction","detail":"{read_only:false; response_revision:10847; number_of_response:1; }","duration":"334.573556ms","start":"2026-10-14T18:04:07.81348Z","end":"2026-10-14T18:04:08.148053Z","steps":["trace[1968304271] 'process raft request' (duration: 332.250365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:08.148125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:07.813424Z","time spent":"334.669548ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:09.693178Z","caller":"traceutil/trace.go:171","msg":"trace[935313496] transaction","detail":"{read_only:false; response_revision:10860; number_of_response:1; }","duration":"108.32544ms","start":"2026-10-14T18:04:09.584813Z","end":"2026-10-14T18:04:09.693138Z","steps":["trace[935313496] 'process raft request' (duration: 42.19541ms)","trace[935313496] 'compare' (duration: 65.946086ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:09.868652Z","caller":"traceutil/trace.go:171","msg":"trace[781281397] transaction","detail":"{read_only:false; response_revision:10864; number_of_response:1; }","duration":"108.510465ms","start":"2026-10-14T18:04:09.7601Z","end":"2026-10-14T18:04:09.868611Z","steps":["trace[781281397] 'process raft request' (duration: 108.388823ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:09.869057Z","caller":"traceutil/trace.go:171","msg":"trace[1712721785] transaction","detail":"{read_only:false; response_revision:10863; number_of_response:1; }","duration":"112.119185ms","start":"2026-10-14T18:04:09.756861Z","end":"2026-10-14T18:04:09.86898Z","steps":["trace[1712721785] 'process raft request' (duration: 77.826327ms)","trace[1712721785] 'compare' (duration: 33.451759ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:10.450414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.438012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:04:10.4508Z","caller":"traceutil/trace.go:171","msg":"trace[105709283] linearizableReadLoop","detail":"{readStateIndex:11250; appliedIndex:11246; }","duration":"136.003572ms","start":"2026-10-14T18:04:10.314754Z","end":"2026-10-14T18:04:10.450757Z","steps":["trace[105709283] 'read index received' (duration: 12.67242ms)","trace[105709283] 'applied index is now lower than readState.Index' (duration: 123.329542ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:10.450946Z","caller":"traceutil/trace.go:171","msg":"trace[912807381] transaction","detail":"{read_only:false; response_revision:10874; number_of_response:1; }","duration":"215.406664ms","start":"2026-10-14T18:04:10.235506Z","end":"2026-10-14T18:04:10.450912Z","steps":["trace[912807381] 'process raft request' (duration: 215.108478ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:10.450962Z","caller":"traceutil/trace.go:171","msg":"trace[76914193] transaction","detail":"{read_only:false; response_revision:10873; number_of_response:1; }","duration":"217.660505ms","start":"2026-10-14T18:04:10.233145Z","end":"2026-10-14T18:04:10.450806Z","steps":["trace[76914193] 'process raft request' (duration: 217.400959ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:10.451286Z","caller":"traceutil/trace.go:171","msg":"trace[2081728191] transaction","detail":"{read_only:false; response_revision:10872; number_of_response:1; }","duration":"219.008915ms","start":"2026-10-14T18:04:10.23224Z","end":"2026-10-14T18:04:10.451249Z","steps":["trace[2081728191] 'process raft request' (duration: 95.184282ms)","trace[2081728191] 'compare' (duration: 122.228498ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:10.451349Z","caller":"traceutil/trace.go:171","msg":"trace[528995701] transaction","detail":"{read_only:false; response_revision:10875; number_of_response:1; }","duration":"214.312351ms","start":"2026-10-14T18:04:10.237017Z","end":"2026-10-14T18:04:10.45133Z","steps":["trace[528995701] 'process raft request' (duration: 213.660556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:10.451445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.683737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:04:10.451581Z","caller":"traceutil/trace.go:171","msg":"trace[1239956051] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:10875; }","duration":"136.869162ms","start":"2026-10-14T18:04:10.314692Z","end":"2026-10-14T18:04:10.451561Z","steps":["trace[1239956051] 'agreement among raft nodes before linearized reading' (duration: 136.669337ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:08:18.508545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10438} {"level":"info","ts":"2026-10-14T18:08:18.660714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10438,"took":"149.351289ms","hash":2899279656,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":28139520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:08:18.660825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2899279656,"revision":10438,"compact-revision":7981} {"level":"info","ts":"2026-10-14T18:11:22.819229Z","caller":"traceutil/trace.go:171","msg":"trace[351286535] linearizableReadLoop","detail":"{readStateIndex:14529; appliedIndex:14528; }","duration":"155.767249ms","start":"2026-10-14T18:11:22.663434Z","end":"2026-10-14T18:11:22.819202Z","steps":["trace[351286535] 'read index received' (duration: 155.525714ms)","trace[351286535] 'applied index is now lower than readState.Index' (duration: 240.095µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:11:22.819326Z","caller":"traceutil/trace.go:171","msg":"trace[907297368] transaction","detail":"{read_only:false; response_revision:14051; number_of_response:1; }","duration":"215.549021ms","start":"2026-10-14T18:11:22.603707Z","end":"2026-10-14T18:11:22.819256Z","steps":["trace[907297368] 'process raft request' (duration: 215.296265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:11:22.819608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.135788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T18:11:22.819704Z","caller":"traceutil/trace.go:171","msg":"trace[810198207] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:14051; }","duration":"156.302452ms","start":"2026-10-14T18:11:22.663376Z","end":"2026-10-14T18:11:22.819678Z","steps":["trace[810198207] 'agreement among raft nodes before linearized reading' (duration: 155.977285ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:53.151965Z","caller":"traceutil/trace.go:171","msg":"trace[1404866944] linearizableReadLoop","detail":"{readStateIndex:14694; appliedIndex:14693; }","duration":"193.881043ms","start":"2026-10-14T18:11:52.958052Z","end":"2026-10-14T18:11:53.151933Z","steps":["trace[1404866944] 'read index received' (duration: 193.678938ms)","trace[1404866944] 'applied index is now lower than readState.Index' (duration: 200.535µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:11:53.152067Z","caller":"traceutil/trace.go:171","msg":"trace[284591993] transaction","detail":"{read_only:false; response_revision:14210; number_of_response:1; }","duration":"197.233666ms","start":"2026-10-14T18:11:52.954785Z","end":"2026-10-14T18:11:53.152019Z","steps":["trace[284591993] 'process raft request' (duration: 196.965471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:11:53.152205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.128079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:11:53.152251Z","caller":"traceutil/trace.go:171","msg":"trace[342590966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:14210; }","duration":"194.255901ms","start":"2026-10-14T18:11:52.957979Z","end":"2026-10-14T18:11:53.152235Z","steps":["trace[342590966] 'agreement among raft nodes before linearized reading' (duration: 194.147369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:11:53.152305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.224832ms","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-10-14T18:11:53.152323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.06428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:11:53.152411Z","caller":"traceutil/trace.go:171","msg":"trace[2122225022] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14210; }","duration":"100.199603ms","start":"2026-10-14T18:11:53.052191Z","end":"2026-10-14T18:11:53.152391Z","steps":["trace[2122225022] 'agreement among raft nodes before linearized reading' (duration: 100.025699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:11:53.152455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.325048ms","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-10-14T18:11:53.152487Z","caller":"traceutil/trace.go:171","msg":"trace[888912853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14210; }","duration":"163.37594ms","start":"2026-10-14T18:11:52.989099Z","end":"2026-10-14T18:11:53.152475Z","steps":["trace[888912853] 'agreement among raft nodes before linearized reading' (duration: 163.287878ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:53.15236Z","caller":"traceutil/trace.go:171","msg":"trace[1881862656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14210; }","duration":"166.336755ms","start":"2026-10-14T18:11:52.986006Z","end":"2026-10-14T18:11:53.152343Z","steps":["trace[1881862656] 'agreement among raft nodes before linearized reading' (duration: 166.13578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.241668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.932556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:12:02.241743Z","caller":"traceutil/trace.go:171","msg":"trace[1547128663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14275; }","duration":"248.055669ms","start":"2026-10-14T18:12:01.99367Z","end":"2026-10-14T18:12:02.241726Z","steps":["trace[1547128663] 'range keys from in-memory index tree' (duration: 247.881025ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:18.518173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12781} {"level":"info","ts":"2026-10-14T18:13:18.613264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12781,"took":"91.332953ms","hash":36922414,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:13:18.613347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":36922414,"revision":12781,"compact-revision":10438} {"level":"info","ts":"2026-10-14T18:13:22.088555Z","caller":"traceutil/trace.go:171","msg":"trace[206497886] transaction","detail":"{read_only:false; response_revision:15372; number_of_response:1; }","duration":"133.182876ms","start":"2026-10-14T18:13:21.955341Z","end":"2026-10-14T18:13:22.088523Z","steps":["trace[206497886] 'process raft request' (duration: 132.92539ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.159129Z","caller":"traceutil/trace.go:171","msg":"trace[1746304234] transaction","detail":"{read_only:false; response_revision:15545; number_of_response:1; }","duration":"220.45679ms","start":"2026-10-14T18:13:55.938621Z","end":"2026-10-14T18:13:56.159078Z","steps":["trace[1746304234] 'process raft request' (duration: 173.150857ms)","trace[1746304234] 'compare' (duration: 46.999426ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:13:56.159925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.382677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:56.160038Z","caller":"traceutil/trace.go:171","msg":"trace[1218004280] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15545; }","duration":"229.51011ms","start":"2026-10-14T18:13:55.930507Z","end":"2026-10-14T18:13:56.160017Z","steps":["trace[1218004280] 'agreement among raft nodes before linearized reading' (duration: 229.332986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.160087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.031785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:13:56.160162Z","caller":"traceutil/trace.go:171","msg":"trace[1041598219] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15545; }","duration":"227.151158ms","start":"2026-10-14T18:13:55.932996Z","end":"2026-10-14T18:13:56.160147Z","steps":["trace[1041598219] 'agreement among raft nodes before linearized reading' (duration: 226.869591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.160594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.521862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:56.16064Z","caller":"traceutil/trace.go:171","msg":"trace[564147606] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15545; }","duration":"230.617234ms","start":"2026-10-14T18:13:55.930011Z","end":"2026-10-14T18:13:56.160629Z","steps":["trace[564147606] 'agreement among raft nodes before linearized reading' (duration: 230.549163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.160786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.44734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:56.160851Z","caller":"traceutil/trace.go:171","msg":"trace[883502038] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15545; }","duration":"230.568824ms","start":"2026-10-14T18:13:55.930268Z","end":"2026-10-14T18:13:56.160837Z","steps":["trace[883502038] 'agreement among raft nodes before linearized reading' (duration: 230.364519ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.15982Z","caller":"traceutil/trace.go:171","msg":"trace[1945971613] linearizableReadLoop","detail":"{readStateIndex:16073; appliedIndex:16072; }","duration":"195.696125ms","start":"2026-10-14T18:13:55.964093Z","end":"2026-10-14T18:13:56.15979Z","steps":["trace[1945971613] 'read index received' (duration: 147.688186ms)","trace[1945971613] 'applied index is now lower than readState.Index' (duration: 48.006259ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:13:56.161502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.657603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T18:13:56.16153Z","caller":"traceutil/trace.go:171","msg":"trace[22231322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15545; }","duration":"146.736565ms","start":"2026-10-14T18:13:56.014784Z","end":"2026-10-14T18:13:56.16152Z","steps":["trace[22231322] 'agreement among raft nodes before linearized reading' (duration: 146.623502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.161718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.745799ms","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-10-14T18:13:56.161745Z","caller":"traceutil/trace.go:171","msg":"trace[1211452143] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:15545; }","duration":"109.818411ms","start":"2026-10-14T18:13:56.051917Z","end":"2026-10-14T18:13:56.161735Z","steps":["trace[1211452143] 'agreement among raft nodes before linearized reading' (duration: 109.762269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.161965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.611315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T18:13:56.162016Z","caller":"traceutil/trace.go:171","msg":"trace[355236709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15545; }","duration":"133.682078ms","start":"2026-10-14T18:13:56.028325Z","end":"2026-10-14T18:13:56.162007Z","steps":["trace[355236709] 'agreement among raft nodes before linearized reading' (duration: 133.570095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.162203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.244508ms","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-10-14T18:13:56.162238Z","caller":"traceutil/trace.go:171","msg":"trace[714120478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15545; }","duration":"138.298639ms","start":"2026-10-14T18:13:56.023929Z","end":"2026-10-14T18:13:56.162227Z","steps":["trace[714120478] 'agreement among raft nodes before linearized reading' (duration: 138.212478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.162392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.908197ms","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-10-14T18:13:56.162427Z","caller":"traceutil/trace.go:171","msg":"trace[202838971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15545; }","duration":"140.974628ms","start":"2026-10-14T18:13:56.021442Z","end":"2026-10-14T18:13:56.162417Z","steps":["trace[202838971] 'agreement among raft nodes before linearized reading' (duration: 140.891556ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.325035Z","caller":"traceutil/trace.go:171","msg":"trace[1960968179] transaction","detail":"{read_only:false; response_revision:15546; number_of_response:1; }","duration":"157.918671ms","start":"2026-10-14T18:13:56.167084Z","end":"2026-10-14T18:13:56.325003Z","steps":["trace[1960968179] 'process raft request' (duration: 108.487982ms)","trace[1960968179] 'compare' (duration: 48.420507ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:13:56.326367Z","caller":"traceutil/trace.go:171","msg":"trace[598680954] transaction","detail":"{read_only:false; response_revision:15547; number_of_response:1; }","duration":"158.410013ms","start":"2026-10-14T18:13:56.167939Z","end":"2026-10-14T18:13:56.326349Z","steps":["trace[598680954] 'process raft request' (duration: 156.860079ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.326922Z","caller":"traceutil/trace.go:171","msg":"trace[996328841] linearizableReadLoop","detail":"{readStateIndex:16077; appliedIndex:16073; }","duration":"145.569429ms","start":"2026-10-14T18:13:56.181336Z","end":"2026-10-14T18:13:56.326905Z","steps":["trace[996328841] 'read index received' (duration: 94.220837ms)","trace[996328841] 'applied index is now lower than readState.Index' (duration: 51.346992ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:13:56.32717Z","caller":"traceutil/trace.go:171","msg":"trace[713995551] transaction","detail":"{read_only:false; response_revision:15548; number_of_response:1; }","duration":"159.066787ms","start":"2026-10-14T18:13:56.16809Z","end":"2026-10-14T18:13:56.327157Z","steps":["trace[713995551] 'process raft request' (duration: 158.597806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.327601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.333394ms","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-10-14T18:13:56.327641Z","caller":"traceutil/trace.go:171","msg":"trace[3105442] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15549; }","duration":"145.411576ms","start":"2026-10-14T18:13:56.182218Z","end":"2026-10-14T18:13:56.32763Z","steps":["trace[3105442] 'agreement among raft nodes before linearized reading' (duration: 145.219562ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.32782Z","caller":"traceutil/trace.go:171","msg":"trace[1867768819] transaction","detail":"{read_only:false; response_revision:15549; number_of_response:1; }","duration":"159.633929ms","start":"2026-10-14T18:13:56.168175Z","end":"2026-10-14T18:13:56.327809Z","steps":["trace[1867768819] 'process raft request' (duration: 158.686908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.327996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.649354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:56.328031Z","caller":"traceutil/trace.go:171","msg":"trace[598595229] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15549; }","duration":"146.735145ms","start":"2026-10-14T18:13:56.181287Z","end":"2026-10-14T18:13:56.328022Z","steps":["trace[598595229] 'agreement among raft nodes before linearized reading' (duration: 146.653953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.328548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.376965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:13:56.328585Z","caller":"traceutil/trace.go:171","msg":"trace[1440333087] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15549; }","duration":"145.451917ms","start":"2026-10-14T18:13:56.183124Z","end":"2026-10-14T18:13:56.328576Z","steps":["trace[1440333087] 'agreement among raft nodes before linearized reading' (duration: 145.359665ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.469241Z","caller":"traceutil/trace.go:171","msg":"trace[2134787931] transaction","detail":"{read_only:false; response_revision:15550; number_of_response:1; }","duration":"134.954275ms","start":"2026-10-14T18:13:56.334239Z","end":"2026-10-14T18:13:56.469193Z","steps":["trace[2134787931] 'process raft request' (duration: 134.654179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.767394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.839339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hj5w6\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-14T18:13:56.76754Z","caller":"traceutil/trace.go:171","msg":"trace[2012998965] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hj5w6; range_end:; response_count:1; response_revision:15550; }","duration":"123.031663ms","start":"2026-10-14T18:13:56.644451Z","end":"2026-10-14T18:13:56.767483Z","steps":["trace[2012998965] 'range keys from in-memory index tree' (duration: 122.519052ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:56.96407Z","caller":"traceutil/trace.go:171","msg":"trace[743815272] linearizableReadLoop","detail":"{readStateIndex:16079; appliedIndex:16078; }","duration":"117.389488ms","start":"2026-10-14T18:13:56.846649Z","end":"2026-10-14T18:13:56.964039Z","steps":["trace[743815272] 'read index received' (duration: 117.143723ms)","trace[743815272] 'applied index is now lower than readState.Index' (duration: 244.815µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:13:56.964377Z","caller":"traceutil/trace.go:171","msg":"trace[1766093025] transaction","detail":"{read_only:false; response_revision:15551; number_of_response:1; }","duration":"127.133032ms","start":"2026-10-14T18:13:56.83723Z","end":"2026-10-14T18:13:56.964363Z","steps":["trace[1766093025] 'process raft request' (duration: 126.649812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:56.964886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.210276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:13:56.964926Z","caller":"traceutil/trace.go:171","msg":"trace[1665510105] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:15551; }","duration":"118.303258ms","start":"2026-10-14T18:13:56.846612Z","end":"2026-10-14T18:13:56.964915Z","steps":["trace[1665510105] 'agreement among raft nodes before linearized reading' (duration: 118.196175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:57.133134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.030115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:57.133294Z","caller":"traceutil/trace.go:171","msg":"trace[1483434212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15551; }","duration":"139.25456ms","start":"2026-10-14T18:13:56.993989Z","end":"2026-10-14T18:13:57.133243Z","steps":["trace[1483434212] 'range keys from in-memory index tree' (duration: 138.949734ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:18.527427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15347} {"level":"info","ts":"2026-10-14T18:18:18.643695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15347,"took":"112.333282ms","hash":1978173885,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":31453184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:18:18.643796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1978173885,"revision":15347,"compact-revision":12781} {"level":"info","ts":"2026-10-14T18:19:28.727682Z","caller":"traceutil/trace.go:171","msg":"trace[1026031573] transaction","detail":"{read_only:false; response_revision:17968; number_of_response:1; }","duration":"116.384592ms","start":"2026-10-14T18:19:28.611263Z","end":"2026-10-14T18:19:28.727648Z","steps":["trace[1026031573] 'process raft request' (duration: 116.065875ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:31.897999Z","caller":"traceutil/trace.go:171","msg":"trace[151817192] transaction","detail":"{read_only:false; response_revision:18042; number_of_response:1; }","duration":"143.906378ms","start":"2026-10-14T18:19:31.754055Z","end":"2026-10-14T18:19:31.897962Z","steps":["trace[151817192] 'process raft request' (duration: 99.124972ms)","trace[151817192] 'compare' (duration: 44.438918ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:19:31.898675Z","caller":"traceutil/trace.go:171","msg":"trace[1683167997] transaction","detail":"{read_only:false; response_revision:18043; number_of_response:1; }","duration":"144.564042ms","start":"2026-10-14T18:19:31.754093Z","end":"2026-10-14T18:19:31.898657Z","steps":["trace[1683167997] 'process raft request' (duration: 143.817996ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:34.119084Z","caller":"traceutil/trace.go:171","msg":"trace[1236987415] transaction","detail":"{read_only:false; response_revision:18057; number_of_response:1; }","duration":"133.947537ms","start":"2026-10-14T18:19:33.985106Z","end":"2026-10-14T18:19:34.119053Z","steps":["trace[1236987415] 'process raft request' (duration: 133.718722ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:01.33563Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b856fd30c5dc1b8","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:22:01.34676Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-10-14T18:22:01.346987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-10-14T18:23:18.534366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17481} {"level":"info","ts":"2026-10-14T18:23:18.643801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17481,"took":"105.316302ms","hash":207911533,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":34381824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T18:23:18.64388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":207911533,"revision":17481,"compact-revision":15347} {"level":"info","ts":"2026-10-14T18:24:37.208762Z","caller":"traceutil/trace.go:171","msg":"trace[1554896759] linearizableReadLoop","detail":"{readStateIndex:21115; appliedIndex:21114; }","duration":"146.9049ms","start":"2026-10-14T18:24:37.061821Z","end":"2026-10-14T18:24:37.208726Z","steps":["trace[1554896759] 'read index received' (duration: 146.86111ms)","trace[1554896759] 'applied index is now lower than readState.Index' (duration: 42.31µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:37.208837Z","caller":"traceutil/trace.go:171","msg":"trace[286913111] transaction","detail":"{read_only:false; response_revision:20422; number_of_response:1; }","duration":"191.182467ms","start":"2026-10-14T18:24:37.017615Z","end":"2026-10-14T18:24:37.208797Z","steps":["trace[286913111] 'process raft request' (duration: 190.904621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:37.212188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.336046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-14T18:24:37.212331Z","caller":"traceutil/trace.go:171","msg":"trace[530438960] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20422; }","duration":"150.54847ms","start":"2026-10-14T18:24:37.061751Z","end":"2026-10-14T18:24:37.2123Z","steps":["trace[530438960] 'agreement among raft nodes before linearized reading' (duration: 147.103434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:42.672908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.699715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T18:24:42.673012Z","caller":"traceutil/trace.go:171","msg":"trace[948691591] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20450; }","duration":"248.859548ms","start":"2026-10-14T18:24:42.42413Z","end":"2026-10-14T18:24:42.672989Z","steps":["trace[948691591] 'count revisions from in-memory index tree' (duration: 248.555642ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:24:59.0554Z","caller":"traceutil/trace.go:171","msg":"trace[1870447132] transaction","detail":"{read_only:false; response_revision:20532; number_of_response:1; }","duration":"216.417413ms","start":"2026-10-14T18:24:58.838933Z","end":"2026-10-14T18:24:59.055351Z","steps":["trace[1870447132] 'process raft request' (duration: 214.91232ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:24:59.426685Z","caller":"traceutil/trace.go:171","msg":"trace[1388327435] transaction","detail":"{read_only:false; response_revision:20533; number_of_response:1; }","duration":"365.031465ms","start":"2026-10-14T18:24:59.06158Z","end":"2026-10-14T18:24:59.426612Z","steps":["trace[1388327435] 'process raft request' (duration: 333.842457ms)","trace[1388327435] 'compare' (duration: 30.588384ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:59.427491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:59.061557Z","time spent":"365.805401ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:59.578261Z","caller":"traceutil/trace.go:171","msg":"trace[279999443] transaction","detail":"{read_only:false; response_revision:20534; number_of_response:1; }","duration":"204.408323ms","start":"2026-10-14T18:24:59.373795Z","end":"2026-10-14T18:24:59.578204Z","steps":["trace[279999443] 'process raft request' (duration: 198.208487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:25:00.124825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.662627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:25:00.12491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.771162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:25:00.124958Z","caller":"traceutil/trace.go:171","msg":"trace[1328319232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20535; }","duration":"129.865751ms","start":"2026-10-14T18:24:59.995062Z","end":"2026-10-14T18:25:00.124928Z","steps":["trace[1328319232] 'range keys from in-memory index tree' (duration: 129.495934ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:25:00.125001Z","caller":"traceutil/trace.go:171","msg":"trace[1409033290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20535; }","duration":"125.908294ms","start":"2026-10-14T18:24:59.999068Z","end":"2026-10-14T18:25:00.124976Z","steps":["trace[1409033290] 'range keys from in-memory index tree' (duration: 125.587477ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:25:00.125114Z","caller":"traceutil/trace.go:171","msg":"trace[1349227676] transaction","detail":"{read_only:false; response_revision:20536; number_of_response:1; }","duration":"124.575465ms","start":"2026-10-14T18:25:00.000494Z","end":"2026-10-14T18:25:00.125069Z","steps":["trace[1349227676] 'process raft request' (duration: 45.143585ms)","trace[1349227676] 'compare' (duration: 79.203495ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:25:00.125346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.524381ms","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-10-14T18:25:00.125425Z","caller":"traceutil/trace.go:171","msg":"trace[466032461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20536; }","duration":"123.641245ms","start":"2026-10-14T18:25:00.001762Z","end":"2026-10-14T18:25:00.125403Z","steps":["trace[466032461] 'agreement among raft nodes before linearized reading' (duration: 123.363398ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:25:00.126487Z","caller":"traceutil/trace.go:171","msg":"trace[1473578435] linearizableReadLoop","detail":"{readStateIndex:21233; appliedIndex:21232; }","duration":"123.256546ms","start":"2026-10-14T18:25:00.001785Z","end":"2026-10-14T18:25:00.125041Z","steps":["trace[1473578435] 'read index received' (duration: 43.723643ms)","trace[1473578435] 'applied index is now lower than readState.Index' (duration: 79.531223ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:25:00.45929Z","caller":"traceutil/trace.go:171","msg":"trace[763494549] transaction","detail":"{read_only:false; response_revision:20537; number_of_response:1; }","duration":"330.328548ms","start":"2026-10-14T18:25:00.128919Z","end":"2026-10-14T18:25:00.459248Z","steps":["trace[763494549] 'process raft request' (duration: 330.066522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:25:00.459595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:25:00.128895Z","time spent":"330.508292ms","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T18:25:00.498599Z","caller":"traceutil/trace.go:171","msg":"trace[1982723974] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"366.853623ms","start":"2026-10-14T18:25:00.131712Z","end":"2026-10-14T18:25:00.498566Z","steps":["trace[1982723974] 'process raft request' (duration: 366.758161ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:25:00.498605Z","caller":"traceutil/trace.go:171","msg":"trace[1200499] transaction","detail":"{read_only:false; response_revision:20538; number_of_response:1; }","duration":"367.234721ms","start":"2026-10-14T18:25:00.131334Z","end":"2026-10-14T18:25:00.498569Z","steps":["trace[1200499] 'process raft request' (duration: 366.940064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:25:00.498795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:25:00.131701Z","time spent":"366.993906ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:25:00.498907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:25:00.13132Z","time spent":"367.487336ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:25:00.544127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.539622ms","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-10-14T18:25:00.54421Z","caller":"traceutil/trace.go:171","msg":"trace[1693502418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20541; }","duration":"123.686335ms","start":"2026-10-14T18:25:00.420508Z","end":"2026-10-14T18:25:00.544194Z","steps":["trace[1693502418] 'agreement among raft nodes before linearized reading' (duration: 123.4468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:25:00.544153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.520381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:25:00.544325Z","caller":"traceutil/trace.go:171","msg":"trace[1758698342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20541; }","duration":"113.707316ms","start":"2026-10-14T18:25:00.430601Z","end":"2026-10-14T18:25:00.544309Z","steps":["trace[1758698342] 'agreement among raft nodes before linearized reading' (duration: 113.46627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:18.541974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19895} {"level":"info","ts":"2026-10-14T18:28:18.67461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19895,"took":"127.429528ms","hash":3402327852,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:28:18.674785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3402327852,"revision":19895,"compact-revision":17481} {"level":"info","ts":"2026-10-14T18:29:00.346172Z","caller":"traceutil/trace.go:171","msg":"trace[1902946540] linearizableReadLoop","detail":"{readStateIndex:23785; appliedIndex:23784; }","duration":"305.660004ms","start":"2026-10-14T18:29:00.040475Z","end":"2026-10-14T18:29:00.346135Z","steps":["trace[1902946540] 'read index received' (duration: 305.558952ms)","trace[1902946540] 'applied index is now lower than readState.Index' (duration: 99.672µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:29:00.346582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.107104ms","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-10-14T18:29:00.346633Z","caller":"traceutil/trace.go:171","msg":"trace[1443741055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23011; }","duration":"306.264887ms","start":"2026-10-14T18:29:00.040353Z","end":"2026-10-14T18:29:00.346618Z","steps":["trace[1443741055] 'agreement among raft nodes before linearized reading' (duration: 305.94615ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:29:00.346627Z","caller":"traceutil/trace.go:171","msg":"trace[985739458] transaction","detail":"{read_only:false; response_revision:23011; number_of_response:1; }","duration":"307.018444ms","start":"2026-10-14T18:29:00.039563Z","end":"2026-10-14T18:29:00.346581Z","steps":["trace[985739458] 'process raft request' (duration: 306.331238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:29:00.346861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:29:00.039505Z","time spent":"307.233699ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:29:00.346885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:29:00.040329Z","time spent":"306.542414ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T18:29:00.389379Z","caller":"traceutil/trace.go:171","msg":"trace[2100543879] transaction","detail":"{read_only:false; response_revision:23013; number_of_response:1; }","duration":"160.818474ms","start":"2026-10-14T18:29:00.228517Z","end":"2026-10-14T18:29:00.389335Z","steps":["trace[2100543879] 'process raft request' (duration: 160.625569ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:29:00.389612Z","caller":"traceutil/trace.go:171","msg":"trace[6297261] transaction","detail":"{read_only:false; response_revision:23012; number_of_response:1; }","duration":"345.469112ms","start":"2026-10-14T18:29:00.044108Z","end":"2026-10-14T18:29:00.389577Z","steps":["trace[6297261] 'process raft request' (duration: 344.414479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:29:00.389793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:29:00.044084Z","time spent":"345.608064ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:29:00.389607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.974671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:29:00.389882Z","caller":"traceutil/trace.go:171","msg":"trace[966919323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23013; }","duration":"232.301458ms","start":"2026-10-14T18:29:00.15755Z","end":"2026-10-14T18:29:00.389851Z","steps":["trace[966919323] 'agreement among raft nodes before linearized reading' (duration: 231.92815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:29:00.389949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.638659ms","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":"warn","ts":"2026-10-14T18:29:00.389588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.010729ms","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-10-14T18:29:00.390025Z","caller":"traceutil/trace.go:171","msg":"trace[223456012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23013; }","duration":"349.52526ms","start":"2026-10-14T18:29:00.040483Z","end":"2026-10-14T18:29:00.390009Z","steps":["trace[223456012] 'agreement among raft nodes before linearized reading' (duration: 348.770914ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:29:00.390067Z","caller":"traceutil/trace.go:171","msg":"trace[1518964295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23014; }","duration":"342.793363ms","start":"2026-10-14T18:29:00.047191Z","end":"2026-10-14T18:29:00.389984Z","steps":["trace[1518964295] 'agreement among raft nodes before linearized reading' (duration: 342.489436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:29:00.390104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:29:00.040443Z","time spent":"349.619893ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T18:29:00.390161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:29:00.047177Z","time spent":"342.971116ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T18:29:00.390364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.029746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-14T18:29:00.390501Z","caller":"traceutil/trace.go:171","msg":"trace[570407868] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23013; }","duration":"298.235261ms","start":"2026-10-14T18:29:00.092243Z","end":"2026-10-14T18:29:00.390478Z","steps":["trace[570407868] 'agreement among raft nodes before linearized reading' (duration: 297.127716ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:25.541366Z","caller":"traceutil/trace.go:171","msg":"trace[1523492742] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"228.965805ms","start":"2026-10-14T18:32:25.31236Z","end":"2026-10-14T18:32:25.541326Z","steps":["trace[1523492742] 'process raft request' (duration: 210.488798ms)","trace[1523492742] 'compare' (duration: 18.299623ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:25.78698Z","caller":"traceutil/trace.go:171","msg":"trace[914910982] linearizableReadLoop","detail":"{readStateIndex:25884; appliedIndex:25882; }","duration":"135.451054ms","start":"2026-10-14T18:32:25.65148Z","end":"2026-10-14T18:32:25.786931Z","steps":["trace[914910982] 'read index received' (duration: 135.155987ms)","trace[914910982] 'applied index is now lower than readState.Index' (duration: 292.997µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:25.787151Z","caller":"traceutil/trace.go:171","msg":"trace[965614159] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"299.288534ms","start":"2026-10-14T18:32:25.487845Z","end":"2026-10-14T18:32:25.787134Z","steps":["trace[965614159] 'process raft request' (duration: 299.005618ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:25.787163Z","caller":"traceutil/trace.go:171","msg":"trace[1341907461] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"440.836723ms","start":"2026-10-14T18:32:25.346267Z","end":"2026-10-14T18:32:25.787104Z","steps":["trace[1341907461] 'process raft request' (duration: 440.321931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:25.787556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.026787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-14T18:32:25.787611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.346239Z","time spent":"441.055788ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:32:25.787711Z","caller":"traceutil/trace.go:171","msg":"trace[746136632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25059; }","duration":"136.232162ms","start":"2026-10-14T18:32:25.65141Z","end":"2026-10-14T18:32:25.787643Z","steps":["trace[746136632] 'agreement among raft nodes before linearized reading' (duration: 135.799862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.10992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.860586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:32:26.110022Z","caller":"traceutil/trace.go:171","msg":"trace[1824250715] linearizableReadLoop","detail":"{readStateIndex:25885; appliedIndex:25884; }","duration":"322.921425ms","start":"2026-10-14T18:32:25.787085Z","end":"2026-10-14T18:32:26.110007Z","steps":["trace[1824250715] 'read index received' (duration: 171.032508ms)","trace[1824250715] 'applied index is now lower than readState.Index' (duration: 151.887697ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:26.110088Z","caller":"traceutil/trace.go:171","msg":"trace[1024226482] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"390.161476ms","start":"2026-10-14T18:32:25.719916Z","end":"2026-10-14T18:32:26.110077Z","steps":["trace[1024226482] 'process raft request' (duration: 238.044495ms)","trace[1024226482] 'compare' (duration: 151.377135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:32:26.11013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.719885Z","time spent":"390.221377ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:32:26.110396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.900266ms","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-10-14T18:32:26.110416Z","caller":"traceutil/trace.go:171","msg":"trace[324021078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25060; }","duration":"456.975508ms","start":"2026-10-14T18:32:25.653433Z","end":"2026-10-14T18:32:26.110408Z","steps":["trace[324021078] 'agreement among raft nodes before linearized reading' (duration: 456.873915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.110401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.006389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T18:32:26.110477Z","caller":"traceutil/trace.go:171","msg":"trace[96904175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25060; }","duration":"447.110661ms","start":"2026-10-14T18:32:25.663347Z","end":"2026-10-14T18:32:26.110457Z","steps":["trace[96904175] 'agreement among raft nodes before linearized reading' (duration: 446.871235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.110515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.663337Z","time spent":"447.169092ms","remote":"127.0.0.1:48708","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":"warn","ts":"2026-10-14T18:32:26.110465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.792524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-14T18:32:26.110436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.653404Z","time spent":"457.027519ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T18:32:26.110607Z","caller":"traceutil/trace.go:171","msg":"trace[1148653840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25060; }","duration":"456.933397ms","start":"2026-10-14T18:32:25.653623Z","end":"2026-10-14T18:32:26.110556Z","steps":["trace[1148653840] 'agreement among raft nodes before linearized reading' (duration: 456.62879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.110679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.653616Z","time spent":"457.046439ms","remote":"127.0.0.1:48708","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":"warn","ts":"2026-10-14T18:32:26.1106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.294859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-14T18:32:26.11071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.34442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:26.110807Z","caller":"traceutil/trace.go:171","msg":"trace[1535301950] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25060; }","duration":"429.526313ms","start":"2026-10-14T18:32:25.681255Z","end":"2026-10-14T18:32:26.110782Z","steps":["trace[1535301950] 'agreement among raft nodes before linearized reading' (duration: 429.298348ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.110787Z","caller":"traceutil/trace.go:171","msg":"trace[1459150901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25060; }","duration":"434.463642ms","start":"2026-10-14T18:32:25.676277Z","end":"2026-10-14T18:32:26.110741Z","steps":["trace[1459150901] 'agreement among raft nodes before linearized reading' (duration: 434.263387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.110864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.681237Z","time spent":"429.611785ms","remote":"127.0.0.1:37134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:32:26.110918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.676265Z","time spent":"434.606675ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T18:32:26.124556Z","caller":"traceutil/trace.go:171","msg":"trace[1312869526] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"331.702158ms","start":"2026-10-14T18:32:25.792835Z","end":"2026-10-14T18:32:26.124537Z","steps":["trace[1312869526] 'process raft request' (duration: 331.493103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.124863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.561257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:32:26.124871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.177324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:32:26.124921Z","caller":"traceutil/trace.go:171","msg":"trace[981626555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25062; }","duration":"130.665889ms","start":"2026-10-14T18:32:25.994239Z","end":"2026-10-14T18:32:26.124905Z","steps":["trace[981626555] 'agreement among raft nodes before linearized reading' (duration: 130.568527ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.124932Z","caller":"traceutil/trace.go:171","msg":"trace[364555641] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25062; }","duration":"266.276886ms","start":"2026-10-14T18:32:25.858636Z","end":"2026-10-14T18:32:26.124913Z","steps":["trace[364555641] 'agreement among raft nodes before linearized reading' (duration: 266.113573ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.124961Z","caller":"traceutil/trace.go:171","msg":"trace[457584556] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"207.688766ms","start":"2026-10-14T18:32:25.917205Z","end":"2026-10-14T18:32:26.124894Z","steps":["trace[457584556] 'process raft request' (duration: 207.280397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.12524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:25.792807Z","time spent":"331.860072ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:32:26.320176Z","caller":"traceutil/trace.go:171","msg":"trace[1693876813] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"204.014694ms","start":"2026-10-14T18:32:26.116132Z","end":"2026-10-14T18:32:26.320147Z","steps":["trace[1693876813] 'process raft request' (duration: 203.760578ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.320506Z","caller":"traceutil/trace.go:171","msg":"trace[606575344] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"203.755098ms","start":"2026-10-14T18:32:26.116739Z","end":"2026-10-14T18:32:26.320494Z","steps":["trace[606575344] 'process raft request' (duration: 203.342029ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.321494Z","caller":"traceutil/trace.go:171","msg":"trace[1932689807] linearizableReadLoop","detail":"{readStateIndex:25891; appliedIndex:25887; }","duration":"191.905047ms","start":"2026-10-14T18:32:26.129563Z","end":"2026-10-14T18:32:26.321468Z","steps":["trace[1932689807] 'read index received' (duration: 190.25091ms)","trace[1932689807] 'applied index is now lower than readState.Index' (duration: 1.653167ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:26.321842Z","caller":"traceutil/trace.go:171","msg":"trace[1942855075] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"204.774711ms","start":"2026-10-14T18:32:26.117054Z","end":"2026-10-14T18:32:26.321829Z","steps":["trace[1942855075] 'process raft request' (duration: 203.311018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.321953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.354866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:26.322066Z","caller":"traceutil/trace.go:171","msg":"trace[937701085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25066; }","duration":"192.487329ms","start":"2026-10-14T18:32:26.129554Z","end":"2026-10-14T18:32:26.322042Z","steps":["trace[937701085] 'agreement among raft nodes before linearized reading' (duration: 192.286745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:26.321958Z","caller":"traceutil/trace.go:171","msg":"trace[298831423] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"204.454223ms","start":"2026-10-14T18:32:26.117454Z","end":"2026-10-14T18:32:26.321908Z","steps":["trace[298831423] 'process raft request' (duration: 203.957252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:26.322142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.338721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-14T18:32:26.322226Z","caller":"traceutil/trace.go:171","msg":"trace[1081808843] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25066; }","duration":"189.486274ms","start":"2026-10-14T18:32:26.132718Z","end":"2026-10-14T18:32:26.322205Z","steps":["trace[1081808843] 'agreement among raft nodes before linearized reading' (duration: 189.209358ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:18.550187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22167} {"level":"info","ts":"2026-10-14T18:33:18.673237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22167,"took":"117.426698ms","hash":1216670774,"current-db-size-bytes":55750656,"current-db-size":"56 MB","current-db-size-in-use-bytes":44478464,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-14T18:33:18.673323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1216670774,"revision":22167,"compact-revision":19895} {"level":"info","ts":"2026-10-14T18:33:29.577538Z","caller":"traceutil/trace.go:171","msg":"trace[1063006883] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"155.118458ms","start":"2026-10-14T18:33:29.422381Z","end":"2026-10-14T18:33:29.5775Z","steps":["trace[1063006883] 'process raft request' (duration: 154.862432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:29.836357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.299298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-14T18:33:29.836441Z","caller":"traceutil/trace.go:171","msg":"trace[1359565391] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25627; }","duration":"237.440521ms","start":"2026-10-14T18:33:29.59898Z","end":"2026-10-14T18:33:29.836421Z","steps":["trace[1359565391] 'range keys from in-memory index tree' (duration: 237.065562ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:29.837409Z","caller":"traceutil/trace.go:171","msg":"trace[630198885] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26467; }","duration":"184.346982ms","start":"2026-10-14T18:33:29.653049Z","end":"2026-10-14T18:33:29.837396Z","steps":["trace[630198885] 'read index received' (duration: 183.627716ms)","trace[630198885] 'applied index is now lower than readState.Index' (duration: 718.016µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:29.837515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.473234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:29.837552Z","caller":"traceutil/trace.go:171","msg":"trace[1664557387] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25628; }","duration":"184.555496ms","start":"2026-10-14T18:33:29.652982Z","end":"2026-10-14T18:33:29.837538Z","steps":["trace[1664557387] 'agreement among raft nodes before linearized reading' (duration: 184.464244ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:29.837671Z","caller":"traceutil/trace.go:171","msg":"trace[510124139] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"219.428865ms","start":"2026-10-14T18:33:29.618185Z","end":"2026-10-14T18:33:29.837614Z","steps":["trace[510124139] 'process raft request' (duration: 218.576166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:30.33635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.789488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:33:30.33662Z","caller":"traceutil/trace.go:171","msg":"trace[758588206] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"276.89952ms","start":"2026-10-14T18:33:30.059698Z","end":"2026-10-14T18:33:30.336598Z","steps":["trace[758588206] 'process raft request' (duration: 276.786678ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:30.337018Z","caller":"traceutil/trace.go:171","msg":"trace[147983992] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"472.139202ms","start":"2026-10-14T18:33:29.864864Z","end":"2026-10-14T18:33:30.337003Z","steps":["trace[147983992] 'process raft request' (duration: 155.567168ms)","trace[147983992] 'compare' (duration: 315.02485ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:30.337076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:29.864824Z","time spent":"472.217034ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:33:30.337215Z","caller":"traceutil/trace.go:171","msg":"trace[354223848] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26468; }","duration":"368.774185ms","start":"2026-10-14T18:33:29.968429Z","end":"2026-10-14T18:33:30.337203Z","steps":["trace[354223848] 'read index received' (duration: 51.993145ms)","trace[354223848] 'applied index is now lower than readState.Index' (duration: 316.77988ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:30.337443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.00964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:30.337471Z","caller":"traceutil/trace.go:171","msg":"trace[1821769903] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25630; }","duration":"369.084062ms","start":"2026-10-14T18:33:29.968378Z","end":"2026-10-14T18:33:30.337462Z","steps":["trace[1821769903] 'agreement among raft nodes before linearized reading' (duration: 369.02252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:30.337499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:29.968352Z","time spent":"369.138553ms","remote":"127.0.0.1:48652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:33:30.33766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.630599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:30.33769Z","caller":"traceutil/trace.go:171","msg":"trace[322955605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25630; }","duration":"342.69784ms","start":"2026-10-14T18:33:29.994983Z","end":"2026-10-14T18:33:30.337681Z","steps":["trace[322955605] 'agreement among raft nodes before linearized reading' (duration: 342.627579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:30.337711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:29.994962Z","time spent":"342.743902ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:33:30.337968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.767397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:33:30.337996Z","caller":"traceutil/trace.go:171","msg":"trace[1818227682] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25630; }","duration":"138.852529ms","start":"2026-10-14T18:33:30.199135Z","end":"2026-10-14T18:33:30.337987Z","steps":["trace[1818227682] 'agreement among raft nodes before linearized reading' (duration: 138.793598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:30.821232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.181517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:33:30.821492Z","caller":"traceutil/trace.go:171","msg":"trace[576803931] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"196.07983ms","start":"2026-10-14T18:33:30.625396Z","end":"2026-10-14T18:33:30.821475Z","steps":["trace[576803931] 'process raft request' (duration: 195.947087ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:30.821899Z","caller":"traceutil/trace.go:171","msg":"trace[291878261] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"446.425034ms","start":"2026-10-14T18:33:30.375461Z","end":"2026-10-14T18:33:30.821886Z","steps":["trace[291878261] 'process raft request' (duration: 261.49341ms)","trace[291878261] 'compare' (duration: 181.650622ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:30.821971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:30.375433Z","time spent":"446.500236ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:33:30.821531Z","caller":"traceutil/trace.go:171","msg":"trace[374042380] linearizableReadLoop","detail":"{readStateIndex:26471; appliedIndex:26470; }","duration":"331.734918ms","start":"2026-10-14T18:33:30.489777Z","end":"2026-10-14T18:33:30.821512Z","steps":["trace[374042380] 'read index received' (duration: 147.252014ms)","trace[374042380] 'applied index is now lower than readState.Index' (duration: 184.480894ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:30.823113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.305362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:33:30.823158Z","caller":"traceutil/trace.go:171","msg":"trace[1613640684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25632; }","duration":"333.427395ms","start":"2026-10-14T18:33:30.489719Z","end":"2026-10-14T18:33:30.823147Z","steps":["trace[1613640684] 'agreement among raft nodes before linearized reading' (duration: 332.379572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:30.823228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:30.489698Z","time spent":"333.519468ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T18:34:04.168706Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e0.wal"} {"level":"info","ts":"2026-10-14T18:38:18.558893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25546} {"level":"info","ts":"2026-10-14T18:38:18.740038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25546,"took":"175.462575ms","hash":745976104,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":35434496,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:38:18.740157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":745976104,"revision":25546,"compact-revision":22167} {"level":"info","ts":"2026-10-14T18:39:50.215845Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b856fd30c5dc1b8","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:39:50.22231Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-14T18:39:50.222461Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-10-14T18:43:18.567109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28402} {"level":"info","ts":"2026-10-14T18:43:18.729105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28402,"took":"153.753182ms","hash":4124971846,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:43:18.729172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4124971846,"revision":28402,"compact-revision":25546} {"level":"info","ts":"2026-10-14T18:48:18.580365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30628} {"level":"info","ts":"2026-10-14T18:48:18.709952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30628,"took":"124.806244ms","hash":760523555,"current-db-size-bytes":57864192,"current-db-size":"58 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:48:18.710023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":760523555,"revision":30628,"compact-revision":28402} {"level":"info","ts":"2026-10-14T18:53:18.587909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32883} {"level":"info","ts":"2026-10-14T18:53:18.775179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32883,"took":"181.311245ms","hash":1271354159,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":29831168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:53:18.775268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1271354159,"revision":32883,"compact-revision":30628} {"level":"info","ts":"2026-10-14T18:54:09.845931Z","caller":"traceutil/trace.go:171","msg":"trace[1253976967] transaction","detail":"{read_only:false; response_revision:35543; number_of_response:1; }","duration":"128.564234ms","start":"2026-10-14T18:54:09.71734Z","end":"2026-10-14T18:54:09.845904Z","steps":["trace[1253976967] 'process raft request' (duration: 128.375ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.484264Z","caller":"traceutil/trace.go:171","msg":"trace[1027795253] transaction","detail":"{read_only:false; response_revision:35547; number_of_response:1; }","duration":"180.313103ms","start":"2026-10-14T18:54:10.303919Z","end":"2026-10-14T18:54:10.484232Z","steps":["trace[1027795253] 'process raft request' (duration: 170.745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.484323Z","caller":"traceutil/trace.go:171","msg":"trace[1870058914] transaction","detail":"{read_only:false; response_revision:35549; number_of_response:1; }","duration":"179.613387ms","start":"2026-10-14T18:54:10.304691Z","end":"2026-10-14T18:54:10.484304Z","steps":["trace[1870058914] 'process raft request' (duration: 179.541616ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.484321Z","caller":"traceutil/trace.go:171","msg":"trace[1307740958] transaction","detail":"{read_only:false; response_revision:35548; number_of_response:1; }","duration":"179.640288ms","start":"2026-10-14T18:54:10.304646Z","end":"2026-10-14T18:54:10.484286Z","steps":["trace[1307740958] 'process raft request' (duration: 179.522485ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.484545Z","caller":"traceutil/trace.go:171","msg":"trace[1812198566] transaction","detail":"{read_only:false; response_revision:35551; number_of_response:1; }","duration":"150.388098ms","start":"2026-10-14T18:54:10.334146Z","end":"2026-10-14T18:54:10.484534Z","steps":["trace[1812198566] 'process raft request' (duration: 150.315627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.484552Z","caller":"traceutil/trace.go:171","msg":"trace[1497642921] transaction","detail":"{read_only:false; response_revision:35550; number_of_response:1; }","duration":"153.997468ms","start":"2026-10-14T18:54:10.330541Z","end":"2026-10-14T18:54:10.484538Z","steps":["trace[1497642921] 'process raft request' (duration: 153.728362ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:54:10.535183Z","caller":"traceutil/trace.go:171","msg":"trace[549378550] linearizableReadLoop","detail":"{readStateIndex:36671; appliedIndex:36665; }","duration":"143.276741ms","start":"2026-10-14T18:54:10.391879Z","end":"2026-10-14T18:54:10.535156Z","steps":["trace[549378550] 'read index received' (duration: 82.962232ms)","trace[549378550] 'applied index is now lower than readState.Index' (duration: 60.313379ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:54:10.535316Z","caller":"traceutil/trace.go:171","msg":"trace[40146432] transaction","detail":"{read_only:false; response_revision:35552; number_of_response:1; }","duration":"196.064952ms","start":"2026-10-14T18:54:10.339241Z","end":"2026-10-14T18:54:10.535305Z","steps":["trace[40146432] 'process raft request' (duration: 195.769536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:54:10.535504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.606488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:54:10.535583Z","caller":"traceutil/trace.go:171","msg":"trace[406131259] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:35552; }","duration":"143.768801ms","start":"2026-10-14T18:54:10.391802Z","end":"2026-10-14T18:54:10.535571Z","steps":["trace[406131259] 'agreement among raft nodes before linearized reading' (duration: 143.638738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:54:10.535856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.337325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:54:10.535973Z","caller":"traceutil/trace.go:171","msg":"trace[363553537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:35552; }","duration":"135.509029ms","start":"2026-10-14T18:54:10.400444Z","end":"2026-10-14T18:54:10.535953Z","steps":["trace[363553537] 'agreement among raft nodes before linearized reading' (duration: 135.199422ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:58:18.596376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35112} {"level":"info","ts":"2026-10-14T18:58:18.749603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35112,"took":"147.113395ms","hash":3323912290,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":32477184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:58:18.749681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3323912290,"revision":35112,"compact-revision":32883} {"level":"info","ts":"2026-10-14T19:01:09.050364Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b856fd30c5dc1b8","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T19:01:09.061177Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-14T19:01:09.061309Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-14T19:03:18.607599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37404} {"level":"info","ts":"2026-10-14T19:03:18.761869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37404,"took":"149.753945ms","hash":2451305206,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":33148928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:03:18.761955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2451305206,"revision":37404,"compact-revision":35112}