{"level":"warn","ts":"2026-09-01T18:24:54.134666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:24:54.134818Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.66: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.66:2380","--initial-cluster=instance=https://162.253.55.66:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.66:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.66:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-01T18:24:54.134975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:24:54.135044Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-09-01T18:24:54.135129Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T18:24:54.135874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"]} {"level":"info","ts":"2026-09-01T18:24:54.136003Z","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":8,"max-cpu-available":8,"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.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.66:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-01T18:24:54.142869Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.578122ms"} {"level":"info","ts":"2026-09-01T18:24:54.153405Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"48afe2e91b68f008","cluster-id":"5894dcc7313657d8"} {"level":"info","ts":"2026-09-01T18:24:54.153494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:24:54.153519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:24:54.153537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 48afe2e91b68f008 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:24:54.153549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:24:54.153593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"warn","ts":"2026-09-01T18:24:54.161599Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:24:54.163136Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:24:54.165957Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-01T18:24:54.171981Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"48afe2e91b68f008","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:24:54.17207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"48afe2e91b68f008","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:24:54.172298Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T18:24:54.172369Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T18:24:54.172788Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T18:24:54.173636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 switched to configuration voters=(5237654382470295560)"} {"level":"info","ts":"2026-09-01T18:24:54.173821Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","added-peer-id":"48afe2e91b68f008","added-peer-peer-urls":["https://162.253.55.66:2380"]} {"level":"info","ts":"2026-09-01T18:24:54.178887Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T18:24:54.178993Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-09-01T18:24:54.179054Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.66:2380"} {"level":"info","ts":"2026-09-01T18:24:54.179124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"48afe2e91b68f008","initial-advertise-peer-urls":["https://162.253.55.66:2380"],"listen-peer-urls":["https://162.253.55.66:2380"],"advertise-client-urls":["https://162.253.55.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:24:54.179156Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:24:54.853781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:24:54.853857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:24:54.854048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgPreVoteResp from 48afe2e91b68f008 at term 1"} {"level":"info","ts":"2026-09-01T18:24:54.854082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:24:54.854179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 received MsgVoteResp from 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-09-01T18:24:54.854278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"48afe2e91b68f008 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:24:54.854352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 48afe2e91b68f008 elected leader 48afe2e91b68f008 at term 2"} {"level":"info","ts":"2026-09-01T18:24:54.855215Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"48afe2e91b68f008","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.66:2379]}","request-path":"/0/members/48afe2e91b68f008/attributes","cluster-id":"5894dcc7313657d8","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:24:54.855365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:24:54.855447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:24:54.855472Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:54.856277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5894dcc7313657d8","local-member-id":"48afe2e91b68f008","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:54.856364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:24:54.856402Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:54.856417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:24:54.856447Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:54.857466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:24:54.858496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.66:2379"} {"level":"info","ts":"2026-09-01T18:24:57.740593Z","caller":"traceutil/trace.go:171","msg":"trace[322328386] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"268.252603ms","start":"2026-09-01T18:24:57.472303Z","end":"2026-09-01T18:24:57.740556Z","steps":["trace[322328386] 'process raft request' (duration: 169.779566ms)","trace[322328386] 'compare' (duration: 98.307358ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:25:00.699257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.59612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:25:00.69941Z","caller":"traceutil/trace.go:171","msg":"trace[2032394278] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"359.04456ms","start":"2026-09-01T18:25:00.340349Z","end":"2026-09-01T18:25:00.699394Z","steps":["trace[2032394278] 'read index received' (duration: 179.676343ms)","trace[2032394278] 'applied index is now lower than readState.Index' (duration: 179.366597ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:25:00.699506Z","caller":"traceutil/trace.go:171","msg":"trace[599747296] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"362.975335ms","start":"2026-09-01T18:25:00.336504Z","end":"2026-09-01T18:25:00.69948Z","steps":["trace[599747296] 'process raft request' (duration: 183.575866ms)","trace[599747296] 'compare' (duration: 178.248834ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:25:00.69954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.103318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-01T18:25:00.699565Z","caller":"traceutil/trace.go:171","msg":"trace[1194155374] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:309; }","duration":"326.151831ms","start":"2026-09-01T18:25:00.373405Z","end":"2026-09-01T18:25:00.699557Z","steps":["trace[1194155374] 'agreement among raft nodes before linearized reading' (duration: 326.085188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:00.699601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.336478Z","time spent":"363.0797ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:25:00.699573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.185987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:25:00.699593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.373388Z","time spent":"326.197373ms","remote":"127.0.0.1:46392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-01T18:25:00.699951Z","caller":"traceutil/trace.go:171","msg":"trace[596760664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:309; }","duration":"359.542983ms","start":"2026-09-01T18:25:00.34028Z","end":"2026-09-01T18:25:00.699823Z","steps":["trace[596760664] 'agreement among raft nodes before linearized reading' (duration: 359.199357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:00.700296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.340269Z","time spent":"359.91789ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-01T18:25:01.192568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.813779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:25:01.192688Z","caller":"traceutil/trace.go:171","msg":"trace[1345780459] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"489.301511ms","start":"2026-09-01T18:25:00.70336Z","end":"2026-09-01T18:25:01.192661Z","steps":["trace[1345780459] 'process raft request' (duration: 183.324414ms)","trace[1345780459] 'compare' (duration: 305.650892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:25:01.192761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.703318Z","time spent":"489.410366ms","remote":"127.0.0.1:46468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T18:25:01.265317Z","caller":"traceutil/trace.go:171","msg":"trace[1050296676] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"444.283601ms","start":"2026-09-01T18:25:00.821011Z","end":"2026-09-01T18:25:01.265294Z","steps":["trace[1050296676] 'read index received' (duration: 65.685854ms)","trace[1050296676] 'applied index is now lower than readState.Index' (duration: 378.596807ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:25:01.26538Z","caller":"traceutil/trace.go:171","msg":"trace[1079635640] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"561.380876ms","start":"2026-09-01T18:25:00.703959Z","end":"2026-09-01T18:25:01.26534Z","steps":["trace[1079635640] 'process raft request' (duration: 561.104784ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:01.265396Z","caller":"traceutil/trace.go:171","msg":"trace[577073343] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"444.343734ms","start":"2026-09-01T18:25:00.820983Z","end":"2026-09-01T18:25:01.265327Z","steps":["trace[577073343] 'process raft request' (duration: 444.207607ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:01.265432Z","caller":"traceutil/trace.go:171","msg":"trace[1293095563] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"444.334704ms","start":"2026-09-01T18:25:00.821064Z","end":"2026-09-01T18:25:01.265399Z","steps":["trace[1293095563] 'process raft request' (duration: 444.170226ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:01.265482Z","caller":"traceutil/trace.go:171","msg":"trace[1640689854] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"441.250588ms","start":"2026-09-01T18:25:00.824219Z","end":"2026-09-01T18:25:01.26547Z","steps":["trace[1640689854] 'process raft request' (duration: 441.048219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:01.265501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.703937Z","time spent":"561.503302ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:25:01.265531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.821031Z","time spent":"444.446349ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:25:01.26554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.820945Z","time spent":"444.525292ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:25:01.26555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.824193Z","time spent":"441.322131ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:25:01.265772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.749353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-09-01T18:25:01.265834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.881603ms","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-09-01T18:25:01.265844Z","caller":"traceutil/trace.go:171","msg":"trace[581306482] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:311; }","duration":"444.864838ms","start":"2026-09-01T18:25:00.820965Z","end":"2026-09-01T18:25:01.26583Z","steps":["trace[581306482] 'agreement among raft nodes before linearized reading' (duration: 444.70227ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:01.265879Z","caller":"traceutil/trace.go:171","msg":"trace[274835030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:311; }","duration":"391.961957ms","start":"2026-09-01T18:25:00.873902Z","end":"2026-09-01T18:25:01.265864Z","steps":["trace[274835030] 'agreement among raft nodes before linearized reading' (duration: 391.897334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:01.265887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.820942Z","time spent":"444.932321ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5535,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-09-01T18:25:01.265771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.632966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:25:01.265914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.873867Z","time spent":"392.03755ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-01T18:25:01.265947Z","caller":"traceutil/trace.go:171","msg":"trace[2049295872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:311; }","duration":"434.837556ms","start":"2026-09-01T18:25:00.831095Z","end":"2026-09-01T18:25:01.265933Z","steps":["trace[2049295872] 'agreement among raft nodes before linearized reading' (duration: 434.587594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:01.265993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:00.831082Z","time spent":"434.893738ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T18:25:47.928587Z","caller":"traceutil/trace.go:171","msg":"trace[1468598220] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"117.50988ms","start":"2026-09-01T18:25:47.811031Z","end":"2026-09-01T18:25:47.92854Z","steps":["trace[1468598220] 'process raft request' (duration: 110.833928ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:47.928959Z","caller":"traceutil/trace.go:171","msg":"trace[1158969194] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"107.898866ms","start":"2026-09-01T18:25:47.820858Z","end":"2026-09-01T18:25:47.928756Z","steps":["trace[1158969194] 'process raft request' (duration: 106.244998ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:48.174651Z","caller":"traceutil/trace.go:171","msg":"trace[895159790] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"143.801814ms","start":"2026-09-01T18:25:48.030828Z","end":"2026-09-01T18:25:48.17463Z","steps":["trace[895159790] 'process raft request' (duration: 143.267418ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:48.174806Z","caller":"traceutil/trace.go:171","msg":"trace[1138257236] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"151.400549ms","start":"2026-09-01T18:25:48.023396Z","end":"2026-09-01T18:25:48.174797Z","steps":["trace[1138257236] 'process raft request' (duration: 134.960873ms)","trace[1138257236] 'compare' (duration: 15.579371ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:25:54.423627Z","caller":"traceutil/trace.go:171","msg":"trace[734200322] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"145.687939ms","start":"2026-09-01T18:25:54.277916Z","end":"2026-09-01T18:25:54.423604Z","steps":["trace[734200322] 'process raft request' (duration: 145.036301ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:55.118513Z","caller":"traceutil/trace.go:171","msg":"trace[2030956242] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"145.532307ms","start":"2026-09-01T18:25:54.972947Z","end":"2026-09-01T18:25:55.118479Z","steps":["trace[2030956242] 'process raft request' (duration: 145.221339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:55.714207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.150065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-01T18:25:55.714256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.562218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:25:55.714288Z","caller":"traceutil/trace.go:171","msg":"trace[46368209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"167.275338ms","start":"2026-09-01T18:25:55.546993Z","end":"2026-09-01T18:25:55.714268Z","steps":["trace[46368209] 'range keys from in-memory index tree' (duration: 166.981001ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:55.714318Z","caller":"traceutil/trace.go:171","msg":"trace[1960874300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"466.66181ms","start":"2026-09-01T18:25:55.247639Z","end":"2026-09-01T18:25:55.714301Z","steps":["trace[1960874300] 'range keys from in-memory index tree' (duration: 466.463534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:55.714359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:55.247618Z","time spent":"466.730022ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T18:25:55.71454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.054264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:25:55.71457Z","caller":"traceutil/trace.go:171","msg":"trace[1479379060] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:681; }","duration":"231.111615ms","start":"2026-09-01T18:25:55.483449Z","end":"2026-09-01T18:25:55.71456Z","steps":["trace[1479379060] 'count revisions from in-memory index tree' (duration: 231.015403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:10.619936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.165418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:26:10.620015Z","caller":"traceutil/trace.go:171","msg":"trace[395459106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"371.290671ms","start":"2026-09-01T18:26:10.248705Z","end":"2026-09-01T18:26:10.619996Z","steps":["trace[395459106] 'range keys from in-memory index tree' (duration: 371.066145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:10.62005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:10.248685Z","time spent":"371.357383ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T18:26:23.468315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.9902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:26:23.468504Z","caller":"traceutil/trace.go:171","msg":"trace[1054863083] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"291.803711ms","start":"2026-09-01T18:26:23.176668Z","end":"2026-09-01T18:26:23.468472Z","steps":["trace[1054863083] 'process raft request' (duration: 26.576277ms)","trace[1054863083] 'compare' (duration: 264.785265ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:23.468852Z","caller":"traceutil/trace.go:171","msg":"trace[918657763] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"272.656473ms","start":"2026-09-01T18:26:23.196183Z","end":"2026-09-01T18:26:23.46884Z","steps":["trace[918657763] 'read index received' (duration: 6.998389ms)","trace[918657763] 'applied index is now lower than readState.Index' (duration: 265.656924ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:26:23.474444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.602501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:26:23.474518Z","caller":"traceutil/trace.go:171","msg":"trace[1523192107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"224.718702ms","start":"2026-09-01T18:26:23.249783Z","end":"2026-09-01T18:26:23.474502Z","steps":["trace[1523192107] 'agreement among raft nodes before linearized reading' (duration: 224.59979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:23.475073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.870856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-09-01T18:26:23.475121Z","caller":"traceutil/trace.go:171","msg":"trace[1691216578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"278.969278ms","start":"2026-09-01T18:26:23.19614Z","end":"2026-09-01T18:26:23.475109Z","steps":["trace[1691216578] 'agreement among raft nodes before linearized reading' (duration: 272.787987ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:50.290175Z","caller":"traceutil/trace.go:171","msg":"trace[204111052] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"101.990033ms","start":"2026-09-01T18:26:50.188152Z","end":"2026-09-01T18:26:50.290142Z","steps":["trace[204111052] 'process raft request' (duration: 76.888052ms)","trace[204111052] 'compare' (duration: 24.751865ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:04.499973Z","caller":"traceutil/trace.go:171","msg":"trace[1516677180] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"129.13485ms","start":"2026-09-01T18:27:04.370817Z","end":"2026-09-01T18:27:04.499952Z","steps":["trace[1516677180] 'process raft request' (duration: 129.016068ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:04.590133Z","caller":"traceutil/trace.go:171","msg":"trace[1643565364] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"218.843812ms","start":"2026-09-01T18:27:04.371256Z","end":"2026-09-01T18:27:04.5901Z","steps":["trace[1643565364] 'process raft request' (duration: 218.639177ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:04.590303Z","caller":"traceutil/trace.go:171","msg":"trace[1274314558] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"209.579187ms","start":"2026-09-01T18:27:04.380697Z","end":"2026-09-01T18:27:04.590276Z","steps":["trace[1274314558] 'process raft request' (duration: 209.352052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:31.326739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.080649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:31.326992Z","caller":"traceutil/trace.go:171","msg":"trace[2039036185] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"187.862315ms","start":"2026-09-01T18:27:31.139044Z","end":"2026-09-01T18:27:31.326906Z","steps":["trace[2039036185] 'process raft request' (duration: 49.523502ms)","trace[2039036185] 'compare' (duration: 137.945588ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:31.655658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.673692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97580552-b960-4c5f-8d48-0d433f0e705f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:27:31.65583Z","caller":"traceutil/trace.go:171","msg":"trace[704463524] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97580552-b960-4c5f-8d48-0d433f0e705f; range_end:; response_count:1; response_revision:1944; }","duration":"197.960927ms","start":"2026-09-01T18:27:31.457836Z","end":"2026-09-01T18:27:31.655797Z","steps":["trace[704463524] 'range keys from in-memory index tree' (duration: 197.396638ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:32.153651Z","caller":"traceutil/trace.go:171","msg":"trace[2057514668] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"493.201686ms","start":"2026-09-01T18:27:31.66042Z","end":"2026-09-01T18:27:32.153622Z","steps":["trace[2057514668] 'process raft request' (duration: 487.059484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:32.153938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:31.660387Z","time spent":"493.399769ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:27:32.153959Z","caller":"traceutil/trace.go:171","msg":"trace[1493293668] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2022; }","duration":"487.577681ms","start":"2026-09-01T18:27:31.66624Z","end":"2026-09-01T18:27:32.153817Z","steps":["trace[1493293668] 'read index received' (duration: 56.042141ms)","trace[1493293668] 'applied index is now lower than readState.Index' (duration: 431.53416ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:32.154167Z","caller":"traceutil/trace.go:171","msg":"trace[382077672] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"486.80938ms","start":"2026-09-01T18:27:31.667308Z","end":"2026-09-01T18:27:32.154117Z","steps":["trace[382077672] 'process raft request' (duration: 486.191691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:32.154319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:31.667294Z","time spent":"486.930811ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:27:32.15428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.986087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97580552-b960-4c5f-8d48-0d433f0e705f\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-09-01T18:27:32.154413Z","caller":"traceutil/trace.go:171","msg":"trace[139704338] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97580552-b960-4c5f-8d48-0d433f0e705f; range_end:; response_count:1; response_revision:1947; }","duration":"488.18232ms","start":"2026-09-01T18:27:31.666201Z","end":"2026-09-01T18:27:32.154383Z","steps":["trace[139704338] 'agreement among raft nodes before linearized reading' (duration: 487.910326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:32.154471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:31.666171Z","time spent":"488.288942ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3765,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97580552-b960-4c5f-8d48-0d433f0e705f\" "} {"level":"warn","ts":"2026-09-01T18:27:32.155125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.742485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-01T18:27:32.155219Z","caller":"traceutil/trace.go:171","msg":"trace[404818171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1948; }","duration":"255.885238ms","start":"2026-09-01T18:27:31.899315Z","end":"2026-09-01T18:27:32.1552Z","steps":["trace[404818171] 'agreement among raft nodes before linearized reading' (duration: 255.665564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:32.155569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.765418ms","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-09-01T18:27:32.155606Z","caller":"traceutil/trace.go:171","msg":"trace[125058762] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1948; }","duration":"221.840498ms","start":"2026-09-01T18:27:31.933755Z","end":"2026-09-01T18:27:32.155596Z","steps":["trace[125058762] 'agreement among raft nodes before linearized reading' (duration: 221.646665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:32.156043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.111268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T18:27:32.156257Z","caller":"traceutil/trace.go:171","msg":"trace[1264047626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1948; }","duration":"195.199358ms","start":"2026-09-01T18:27:31.960883Z","end":"2026-09-01T18:27:32.156082Z","steps":["trace[1264047626] 'agreement among raft nodes before linearized reading' (duration: 194.752992ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:32.155172Z","caller":"traceutil/trace.go:171","msg":"trace[2142501897] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"207.172678ms","start":"2026-09-01T18:27:31.947939Z","end":"2026-09-01T18:27:32.15511Z","steps":["trace[2142501897] 'process raft request' (duration: 206.943565ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:44.526612Z","caller":"traceutil/trace.go:171","msg":"trace[1759464390] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"299.919803ms","start":"2026-09-01T18:27:44.226649Z","end":"2026-09-01T18:27:44.526569Z","steps":["trace[1759464390] 'read index received' (duration: 297.324436ms)","trace[1759464390] 'applied index is now lower than readState.Index' (duration: 2.593887ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:44.526741Z","caller":"traceutil/trace.go:171","msg":"trace[1593338211] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"307.631074ms","start":"2026-09-01T18:27:44.219009Z","end":"2026-09-01T18:27:44.52664Z","steps":["trace[1593338211] 'process raft request' (duration: 306.158983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:44.526956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:44.218991Z","time spent":"307.841458ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:27:44.585165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.646905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:27:44.585262Z","caller":"traceutil/trace.go:171","msg":"trace[404223258] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2048; }","duration":"177.779867ms","start":"2026-09-01T18:27:44.407459Z","end":"2026-09-01T18:27:44.585239Z","steps":["trace[404223258] 'agreement among raft nodes before linearized reading' (duration: 119.61547ms)","trace[404223258] 'count revisions from in-memory index tree' (duration: 57.994374ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:44.585313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.015707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-09-01T18:27:44.585395Z","caller":"traceutil/trace.go:171","msg":"trace[1296355485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2048; }","duration":"346.125648ms","start":"2026-09-01T18:27:44.239247Z","end":"2026-09-01T18:27:44.585373Z","steps":["trace[1296355485] 'agreement among raft nodes before linearized reading' (duration: 287.577756ms)","trace[1296355485] 'range keys from in-memory index tree' (duration: 58.292919ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:44.585448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:44.239223Z","time spent":"346.21542ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":511,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T18:27:44.585574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.294888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-01T18:27:44.585576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.865031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-01T18:27:44.585635Z","caller":"traceutil/trace.go:171","msg":"trace[2130632337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2048; }","duration":"343.372358ms","start":"2026-09-01T18:27:44.242243Z","end":"2026-09-01T18:27:44.585616Z","steps":["trace[2130632337] 'agreement among raft nodes before linearized reading' (duration: 284.856937ms)","trace[2130632337] 'range keys from in-memory index tree' (duration: 58.248278ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:44.585806Z","caller":"traceutil/trace.go:171","msg":"trace[519984847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2048; }","duration":"359.143715ms","start":"2026-09-01T18:27:44.226582Z","end":"2026-09-01T18:27:44.585726Z","steps":["trace[519984847] 'agreement among raft nodes before linearized reading' (duration: 300.210897ms)","trace[519984847] 'range keys from in-memory index tree' (duration: 58.494641ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:44.585847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:44.242238Z","time spent":"343.597882ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T18:27:44.585872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:44.226571Z","time spent":"359.288447ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-01T18:27:44.585932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.16932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-01T18:27:44.586408Z","caller":"traceutil/trace.go:171","msg":"trace[1348729494] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2048; }","duration":"152.863049ms","start":"2026-09-01T18:27:44.433106Z","end":"2026-09-01T18:27:44.585969Z","steps":["trace[1348729494] 'agreement among raft nodes before linearized reading' (duration: 93.747459ms)","trace[1348729494] 'range keys from in-memory index tree' (duration: 57.72155ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:06.996114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.992268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:28:06.996218Z","caller":"traceutil/trace.go:171","msg":"trace[277253025] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"230.224272ms","start":"2026-09-01T18:28:06.765976Z","end":"2026-09-01T18:28:06.9962Z","steps":["trace[277253025] 'read index received' (duration: 14.013451ms)","trace[277253025] 'applied index is now lower than readState.Index' (duration: 216.209481ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:06.996309Z","caller":"traceutil/trace.go:171","msg":"trace[1279571682] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"261.759821ms","start":"2026-09-01T18:28:06.734512Z","end":"2026-09-01T18:28:06.996272Z","steps":["trace[1279571682] 'process raft request' (duration: 45.513549ms)","trace[1279571682] 'compare' (duration: 215.813086ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:06.996422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.446315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-01T18:28:06.996457Z","caller":"traceutil/trace.go:171","msg":"trace[303248133] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2186; }","duration":"230.499455ms","start":"2026-09-01T18:28:06.765942Z","end":"2026-09-01T18:28:06.996441Z","steps":["trace[303248133] 'agreement among raft nodes before linearized reading' (duration: 230.328703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.065227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.531715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-01T18:28:07.065233Z","caller":"traceutil/trace.go:171","msg":"trace[889795809] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"212.435107ms","start":"2026-09-01T18:28:06.852757Z","end":"2026-09-01T18:28:07.065192Z","steps":["trace[889795809] 'process raft request' (duration: 212.093113ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:07.065287Z","caller":"traceutil/trace.go:171","msg":"trace[1976135922] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"264.611335ms","start":"2026-09-01T18:28:06.80066Z","end":"2026-09-01T18:28:07.065271Z","steps":["trace[1976135922] 'agreement among raft nodes before linearized reading' (duration: 264.458373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.065324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.830961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-01T18:28:07.065321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.719406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-01T18:28:07.065388Z","caller":"traceutil/trace.go:171","msg":"trace[1404201724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"298.899972ms","start":"2026-09-01T18:28:06.766455Z","end":"2026-09-01T18:28:07.065355Z","steps":["trace[1404201724] 'agreement among raft nodes before linearized reading' (duration: 298.76901ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:07.065419Z","caller":"traceutil/trace.go:171","msg":"trace[55955715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2187; }","duration":"283.864318ms","start":"2026-09-01T18:28:06.781536Z","end":"2026-09-01T18:28:07.0654Z","steps":["trace[55955715] 'agreement among raft nodes before linearized reading' (duration: 283.602045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.065509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.551878ms","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-09-01T18:28:07.065574Z","caller":"traceutil/trace.go:171","msg":"trace[1098536985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2187; }","duration":"184.662579ms","start":"2026-09-01T18:28:06.880896Z","end":"2026-09-01T18:28:07.065558Z","steps":["trace[1098536985] 'agreement among raft nodes before linearized reading' (duration: 184.398686ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:07.418441Z","caller":"traceutil/trace.go:171","msg":"trace[1365614577] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2274; }","duration":"353.356964ms","start":"2026-09-01T18:28:07.065059Z","end":"2026-09-01T18:28:07.418415Z","steps":["trace[1365614577] 'read index received' (duration: 353.269973ms)","trace[1365614577] 'applied index is now lower than readState.Index' (duration: 85.891µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:07.418569Z","caller":"traceutil/trace.go:171","msg":"trace[1867423814] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"417.649657ms","start":"2026-09-01T18:28:07.000907Z","end":"2026-09-01T18:28:07.418556Z","steps":["trace[1867423814] 'process raft request' (duration: 417.328303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.418788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:07.000879Z","time spent":"417.720408ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:28:07.418849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.612403ms","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-09-01T18:28:07.419138Z","caller":"traceutil/trace.go:171","msg":"trace[379871620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2188; }","duration":"354.940926ms","start":"2026-09-01T18:28:07.064181Z","end":"2026-09-01T18:28:07.419122Z","steps":["trace[379871620] 'agreement among raft nodes before linearized reading' (duration: 354.52321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.419186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:07.064163Z","time spent":"355.012817ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-01T18:28:07.439003Z","caller":"traceutil/trace.go:171","msg":"trace[2139795671] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"368.317708ms","start":"2026-09-01T18:28:07.07066Z","end":"2026-09-01T18:28:07.438978Z","steps":["trace[2139795671] 'process raft request' (duration: 368.191226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.439028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.133397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:28:07.439118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:07.070648Z","time spent":"368.404519ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:07.439235Z","caller":"traceutil/trace.go:171","msg":"trace[409022476] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"369.19407ms","start":"2026-09-01T18:28:07.070022Z","end":"2026-09-01T18:28:07.439216Z","steps":["trace[409022476] 'process raft request' (duration: 368.663303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.43935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:07.069998Z","time spent":"369.283411ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:07.43913Z","caller":"traceutil/trace.go:171","msg":"trace[1039945490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"189.312859ms","start":"2026-09-01T18:28:07.249794Z","end":"2026-09-01T18:28:07.439107Z","steps":["trace[1039945490] 'agreement among raft nodes before linearized reading' (duration: 189.156537ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:07.439183Z","caller":"traceutil/trace.go:171","msg":"trace[1008069451] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"367.124051ms","start":"2026-09-01T18:28:07.072046Z","end":"2026-09-01T18:28:07.43917Z","steps":["trace[1008069451] 'process raft request' (duration: 366.852798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:07.439908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:07.072016Z","time spent":"367.848021ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:28:07.728921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.121506ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:28:07.729055Z","caller":"traceutil/trace.go:171","msg":"trace[1251281010] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2192; }","duration":"200.266077ms","start":"2026-09-01T18:28:07.528764Z","end":"2026-09-01T18:28:07.72903Z","steps":["trace[1251281010] 'range keys from in-memory index tree' (duration: 200.106325ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:14.273008Z","caller":"traceutil/trace.go:171","msg":"trace[359574580] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"185.056074ms","start":"2026-09-01T18:29:14.087922Z","end":"2026-09-01T18:29:14.272978Z","steps":["trace[359574580] 'process raft request' (duration: 184.830654ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:17.484036Z","caller":"traceutil/trace.go:171","msg":"trace[287881638] linearizableReadLoop","detail":"{readStateIndex:2753; appliedIndex:2752; }","duration":"156.167477ms","start":"2026-09-01T18:29:17.327844Z","end":"2026-09-01T18:29:17.484011Z","steps":["trace[287881638] 'read index received' (duration: 113.690109ms)","trace[287881638] 'applied index is now lower than readState.Index' (duration: 42.476198ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:29:17.484267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.407879ms","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-09-01T18:29:17.484301Z","caller":"traceutil/trace.go:171","msg":"trace[1250518731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2650; }","duration":"156.481942ms","start":"2026-09-01T18:29:17.327808Z","end":"2026-09-01T18:29:17.48429Z","steps":["trace[1250518731] 'agreement among raft nodes before linearized reading' (duration: 156.308654ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:17.484393Z","caller":"traceutil/trace.go:171","msg":"trace[1451646977] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"182.538231ms","start":"2026-09-01T18:29:17.30183Z","end":"2026-09-01T18:29:17.484369Z","steps":["trace[1451646977] 'process raft request' (duration: 139.718076ms)","trace[1451646977] 'compare' (duration: 42.359543ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:34:16.756731Z","caller":"traceutil/trace.go:171","msg":"trace[1211834307] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"247.346746ms","start":"2026-09-01T18:34:16.509365Z","end":"2026-09-01T18:34:16.756712Z","steps":["trace[1211834307] 'process raft request' (duration: 247.216723ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:16.757051Z","caller":"traceutil/trace.go:171","msg":"trace[695297595] linearizableReadLoop","detail":"{readStateIndex:5036; appliedIndex:5036; }","duration":"177.215916ms","start":"2026-09-01T18:34:16.579828Z","end":"2026-09-01T18:34:16.757044Z","steps":["trace[695297595] 'read index received' (duration: 177.211776ms)","trace[695297595] 'applied index is now lower than readState.Index' (duration: 3.74µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:34:16.757128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.292557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:34:16.757151Z","caller":"traceutil/trace.go:171","msg":"trace[1235375383] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4851; }","duration":"177.360348ms","start":"2026-09-01T18:34:16.579784Z","end":"2026-09-01T18:34:16.757145Z","steps":["trace[1235375383] 'agreement among raft nodes before linearized reading' (duration: 177.300227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:16.804407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.930296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1f3eacc-3019-4f8d-bdc7-91e979c66c58\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-09-01T18:34:16.804485Z","caller":"traceutil/trace.go:171","msg":"trace[429845704] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1f3eacc-3019-4f8d-bdc7-91e979c66c58; range_end:; response_count:1; response_revision:4852; }","duration":"215.127789ms","start":"2026-09-01T18:34:16.589338Z","end":"2026-09-01T18:34:16.804465Z","steps":["trace[429845704] 'agreement among raft nodes before linearized reading' (duration: 214.898025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:16.804696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.059731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:34:16.804804Z","caller":"traceutil/trace.go:171","msg":"trace[1331177901] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4852; }","duration":"110.174643ms","start":"2026-09-01T18:34:16.694617Z","end":"2026-09-01T18:34:16.804791Z","steps":["trace[1331177901] 'agreement among raft nodes before linearized reading' (duration: 109.507091ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:26.684221Z","caller":"traceutil/trace.go:171","msg":"trace[1338295238] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"156.779066ms","start":"2026-09-01T18:34:26.527418Z","end":"2026-09-01T18:34:26.684197Z","steps":["trace[1338295238] 'process raft request' (duration: 156.620973ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:41.55869Z","caller":"traceutil/trace.go:171","msg":"trace[411020542] linearizableReadLoop","detail":"{readStateIndex:5450; appliedIndex:5449; }","duration":"136.034658ms","start":"2026-09-01T18:34:41.422632Z","end":"2026-09-01T18:34:41.558667Z","steps":["trace[411020542] 'read index received' (duration: 50.526985ms)","trace[411020542] 'applied index is now lower than readState.Index' (duration: 85.506743ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:34:41.558909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.271942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T18:34:41.558946Z","caller":"traceutil/trace.go:171","msg":"trace[934852321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5253; }","duration":"136.366324ms","start":"2026-09-01T18:34:41.42257Z","end":"2026-09-01T18:34:41.558936Z","steps":["trace[934852321] 'agreement among raft nodes before linearized reading' (duration: 136.192871ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:41.558843Z","caller":"traceutil/trace.go:171","msg":"trace[1934103379] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"216.940688ms","start":"2026-09-01T18:34:41.341864Z","end":"2026-09-01T18:34:41.558804Z","steps":["trace[1934103379] 'process raft request' (duration: 131.347693ms)","trace[1934103379] 'compare' (duration: 85.34249ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:34:41.85426Z","caller":"traceutil/trace.go:171","msg":"trace[351148700] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"291.247458ms","start":"2026-09-01T18:34:41.562984Z","end":"2026-09-01T18:34:41.854231Z","steps":["trace[351148700] 'process raft request' (duration: 269.034411ms)","trace[351148700] 'compare' (duration: 22.021104ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:34:41.854259Z","caller":"traceutil/trace.go:171","msg":"trace[134147580] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"150.470313ms","start":"2026-09-01T18:34:41.703748Z","end":"2026-09-01T18:34:41.854219Z","steps":["trace[134147580] 'process raft request' (duration: 150.399972ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:41.854246Z","caller":"traceutil/trace.go:171","msg":"trace[1496450325] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5450; }","duration":"287.387658ms","start":"2026-09-01T18:34:41.566826Z","end":"2026-09-01T18:34:41.854213Z","steps":["trace[1496450325] 'read index received' (duration: 265.206132ms)","trace[1496450325] 'applied index is now lower than readState.Index' (duration: 22.178006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:34:41.854485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.645742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-01T18:34:41.854532Z","caller":"traceutil/trace.go:171","msg":"trace[423819040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5255; }","duration":"287.729774ms","start":"2026-09-01T18:34:41.566789Z","end":"2026-09-01T18:34:41.854519Z","steps":["trace[423819040] 'agreement among raft nodes before linearized reading' (duration: 287.5104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.854503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.935099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:34:41.854568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.86504ms","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-09-01T18:34:41.854585Z","caller":"traceutil/trace.go:171","msg":"trace[373672428] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5255; }","duration":"282.05608ms","start":"2026-09-01T18:34:41.572514Z","end":"2026-09-01T18:34:41.85457Z","steps":["trace[373672428] 'agreement among raft nodes before linearized reading' (duration: 281.941288ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:41.854601Z","caller":"traceutil/trace.go:171","msg":"trace[566725354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5255; }","duration":"109.918521ms","start":"2026-09-01T18:34:41.744673Z","end":"2026-09-01T18:34:41.854591Z","steps":["trace[566725354] 'agreement among raft nodes before linearized reading' (duration: 109.816319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.854755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.248434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:34:41.854776Z","caller":"traceutil/trace.go:171","msg":"trace[1518060045] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5255; }","duration":"176.320326ms","start":"2026-09-01T18:34:41.67845Z","end":"2026-09-01T18:34:41.85477Z","steps":["trace[1518060045] 'agreement among raft nodes before linearized reading' (duration: 176.270945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.854814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.402145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:34:41.854841Z","caller":"traceutil/trace.go:171","msg":"trace[1221974447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5255; }","duration":"230.453686ms","start":"2026-09-01T18:34:41.624379Z","end":"2026-09-01T18:34:41.854833Z","steps":["trace[1221974447] 'agreement among raft nodes before linearized reading' (duration: 230.372244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.854846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.996339ms","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-09-01T18:34:41.854892Z","caller":"traceutil/trace.go:171","msg":"trace[1921763770] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5255; }","duration":"112.061581ms","start":"2026-09-01T18:34:41.742813Z","end":"2026-09-01T18:34:41.854875Z","steps":["trace[1921763770] 'agreement among raft nodes before linearized reading' (duration: 111.945389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.854985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.815662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-01T18:34:41.855019Z","caller":"traceutil/trace.go:171","msg":"trace[525833718] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5255; }","duration":"177.887984ms","start":"2026-09-01T18:34:41.67712Z","end":"2026-09-01T18:34:41.855008Z","steps":["trace[525833718] 'agreement among raft nodes before linearized reading' (duration: 177.765182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:41.855606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.908325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-01T18:34:41.855668Z","caller":"traceutil/trace.go:171","msg":"trace[2056442232] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5255; }","duration":"147.996656ms","start":"2026-09-01T18:34:41.707652Z","end":"2026-09-01T18:34:41.855649Z","steps":["trace[2056442232] 'agreement among raft nodes before linearized reading' (duration: 146.807455ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:54.889055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2874} {"level":"info","ts":"2026-09-01T18:34:54.979042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2874,"took":"88.53646ms","hash":308651219,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":27643904,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:34:54.979259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":308651219,"revision":2874,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:35:32.369962Z","caller":"traceutil/trace.go:171","msg":"trace[754685750] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"130.130836ms","start":"2026-09-01T18:35:32.23979Z","end":"2026-09-01T18:35:32.369921Z","steps":["trace[754685750] 'process raft request' (duration: 35.212656ms)","trace[754685750] 'compare' (duration: 94.545153ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:32.433574Z","caller":"traceutil/trace.go:171","msg":"trace[736803754] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"193.087147ms","start":"2026-09-01T18:35:32.240459Z","end":"2026-09-01T18:35:32.433546Z","steps":["trace[736803754] 'process raft request' (duration: 192.907454ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.434216Z","caller":"traceutil/trace.go:171","msg":"trace[779864973] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"193.554125ms","start":"2026-09-01T18:35:32.240633Z","end":"2026-09-01T18:35:32.434187Z","steps":["trace[779864973] 'process raft request' (duration: 192.874553ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.434324Z","caller":"traceutil/trace.go:171","msg":"trace[1345575240] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"192.71861ms","start":"2026-09-01T18:35:32.241572Z","end":"2026-09-01T18:35:32.43429Z","steps":["trace[1345575240] 'process raft request' (duration: 192.578737ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.43422Z","caller":"traceutil/trace.go:171","msg":"trace[1861768085] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"192.642079ms","start":"2026-09-01T18:35:32.241557Z","end":"2026-09-01T18:35:32.434199Z","steps":["trace[1861768085] 'process raft request' (duration: 192.487806ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:54.895588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5346} {"level":"info","ts":"2026-09-01T18:39:55.007865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5346,"took":"110.760286ms","hash":4221847605,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":18821120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-01T18:39:55.007932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221847605,"revision":5346,"compact-revision":2874}