{"level":"warn","ts":"2026-09-11T17:25:23.426191Z","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-11T17:25:23.426311Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-11T17:25:23.426393Z","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-11T17:25:23.426412Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-09-11T17:25:23.426454Z","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-11T17:25:23.427175Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-09-11T17:25:23.427253Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-11T17:25:23.432825Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.332159ms"} {"level":"info","ts":"2026-09-11T17:25:23.442916Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-09-11T17:25:23.443222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T17:25:23.44338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-09-11T17:25:23.443551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T17:25:23.443633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-09-11T17:25:23.443721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-09-11T17:25:23.455602Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T17:25:23.469301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T17:25:23.47122Z","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-11T17:25:23.474706Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T17:25:23.474939Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T17:25:23.475633Z","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-11T17:25:23.476272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-09-11T17:25:23.476371Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-09-11T17:25:23.476414Z","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-11T17:25:23.476431Z","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-11T17:25:23.477759Z","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-11T17:25:23.478031Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T17:25:23.47807Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T17:25:23.478369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-09-11T17:25:23.478409Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-09-11T17:25:23.844168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T17:25:23.844377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T17:25:23.844471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-09-11T17:25:23.844572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-09-11T17:25:23.844625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-09-11T17:25:23.844688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-09-11T17:25:23.844737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-09-11T17:25:23.885551Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:25:23.888999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:25:23.889054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:25:23.888985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T17:25:23.889827Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T17:25:23.889937Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T17:25:23.891424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-09-11T17:25:23.89159Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:25:23.894219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:25:23.894366Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:25:23.895694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T17:25:25.980166Z","caller":"traceutil/trace.go:171","msg":"trace[1922852593] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"374.741125ms","start":"2026-09-11T17:25:25.605359Z","end":"2026-09-11T17:25:25.9801Z","steps":["trace[1922852593] 'process raft request' (duration: 325.843818ms)","trace[1922852593] 'compare' (duration: 48.597945ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:25:25.981199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:25:25.605332Z","time spent":"375.014057ms","remote":"127.0.0.1:48314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T17:25:26.446069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.902064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-11T17:25:26.446188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.415952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-11T17:25:26.446069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.929803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-11T17:25:26.446235Z","caller":"traceutil/trace.go:171","msg":"trace[1329390437] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"278.513603ms","start":"2026-09-11T17:25:26.167705Z","end":"2026-09-11T17:25:26.446219Z","steps":["trace[1329390437] 'range keys from in-memory index tree' (duration: 278.300102ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:26.446279Z","caller":"traceutil/trace.go:171","msg":"trace[1426212869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"229.174435ms","start":"2026-09-11T17:25:26.217054Z","end":"2026-09-11T17:25:26.446228Z","steps":["trace[1426212869] 'range keys from in-memory index tree' (duration: 228.793682ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:26.446196Z","caller":"traceutil/trace.go:171","msg":"trace[1435542999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"171.080536ms","start":"2026-09-11T17:25:26.275094Z","end":"2026-09-11T17:25:26.446175Z","steps":["trace[1435542999] 'range keys from in-memory index tree' (duration: 170.789024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:26.446069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.13257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-11T17:25:26.446564Z","caller":"traceutil/trace.go:171","msg":"trace[191968904] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"278.748685ms","start":"2026-09-11T17:25:26.167803Z","end":"2026-09-11T17:25:26.446551Z","steps":["trace[191968904] 'range keys from in-memory index tree' (duration: 277.97924ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:13.611614Z","caller":"traceutil/trace.go:171","msg":"trace[376204986] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"111.040734ms","start":"2026-09-11T17:26:13.500551Z","end":"2026-09-11T17:26:13.611591Z","steps":["trace[376204986] 'process raft request' (duration: 110.924733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:13.873435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.566552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:26:13.873644Z","caller":"traceutil/trace.go:171","msg":"trace[1476206517] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"126.75572ms","start":"2026-09-11T17:26:13.74683Z","end":"2026-09-11T17:26:13.873586Z","steps":["trace[1476206517] 'read index received' (duration: 69.85µs)","trace[1476206517] 'applied index is now lower than readState.Index' (duration: 126.68478ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:26:13.873665Z","caller":"traceutil/trace.go:171","msg":"trace[141833597] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"191.843192ms","start":"2026-09-11T17:26:13.681798Z","end":"2026-09-11T17:26:13.873642Z","steps":["trace[141833597] 'process raft request' (duration: 28.639416ms)","trace[141833597] 'compare' (duration: 162.22957ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:13.873843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.006802ms","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-11T17:26:13.873881Z","caller":"traceutil/trace.go:171","msg":"trace[651848670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"127.069223ms","start":"2026-09-11T17:26:13.746797Z","end":"2026-09-11T17:26:13.873867Z","steps":["trace[651848670] 'agreement among raft nodes before linearized reading' (duration: 126.910292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:13.931735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.171089ms","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-11T17:26:13.931875Z","caller":"traceutil/trace.go:171","msg":"trace[878150049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"154.34405ms","start":"2026-09-11T17:26:13.777515Z","end":"2026-09-11T17:26:13.931859Z","steps":["trace[878150049] 'agreement among raft nodes before linearized reading' (duration: 154.108378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:14.290145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.74502ms","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-11T17:26:14.290335Z","caller":"traceutil/trace.go:171","msg":"trace[1728922578] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:603; }","duration":"253.966522ms","start":"2026-09-11T17:26:14.036345Z","end":"2026-09-11T17:26:14.290312Z","steps":["trace[1728922578] 'range keys from in-memory index tree' (duration: 253.72707ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:26:14.290632Z","caller":"traceutil/trace.go:171","msg":"trace[1590358759] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"353.559465ms","start":"2026-09-11T17:26:13.937039Z","end":"2026-09-11T17:26:14.290599Z","steps":["trace[1590358759] 'process raft request' (duration: 341.524741ms)","trace[1590358759] 'compare' (duration: 11.277579ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:14.290799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:26:13.936987Z","time spent":"353.708366ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:26:14.532525Z","caller":"traceutil/trace.go:171","msg":"trace[1938131349] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"440.6259ms","start":"2026-09-11T17:26:14.091868Z","end":"2026-09-11T17:26:14.532494Z","steps":["trace[1938131349] 'read index received' (duration: 186.753359ms)","trace[1938131349] 'applied index is now lower than readState.Index' (duration: 253.871391ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:26:14.532882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.974752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-11T17:26:14.532928Z","caller":"traceutil/trace.go:171","msg":"trace[1547007678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"441.111092ms","start":"2026-09-11T17:26:14.091804Z","end":"2026-09-11T17:26:14.532915Z","steps":["trace[1547007678] 'agreement among raft nodes before linearized reading' (duration: 440.874131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:14.532966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:26:14.091785Z","time spent":"441.172643ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-11T17:26:14.53268Z","caller":"traceutil/trace.go:171","msg":"trace[246775302] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"522.072501ms","start":"2026-09-11T17:26:14.010555Z","end":"2026-09-11T17:26:14.532627Z","steps":["trace[246775302] 'process raft request' (duration: 511.715557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:26:14.533961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:26:14.01052Z","time spent":"523.357378ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T17:26:33.653999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.763439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:26:33.654117Z","caller":"traceutil/trace.go:171","msg":"trace[283602440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"160.946851ms","start":"2026-09-11T17:26:33.493143Z","end":"2026-09-11T17:26:33.65409Z","steps":["trace[283602440] 'range keys from in-memory index tree' (duration: 160.365092ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:27:07.54861Z","caller":"traceutil/trace.go:171","msg":"trace[2034771249] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"123.175647ms","start":"2026-09-11T17:27:07.425404Z","end":"2026-09-11T17:27:07.54858Z","steps":["trace[2034771249] 'read index received' (duration: 123.050391ms)","trace[2034771249] 'applied index is now lower than readState.Index' (duration: 123.856µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:27:07.548871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.451509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T17:27:07.548973Z","caller":"traceutil/trace.go:171","msg":"trace[2038708948] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1194; }","duration":"123.613267ms","start":"2026-09-11T17:27:07.425348Z","end":"2026-09-11T17:27:07.548961Z","steps":["trace[2038708948] 'agreement among raft nodes before linearized reading' (duration: 123.450879ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:27:54.240226Z","caller":"traceutil/trace.go:171","msg":"trace[162169645] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"137.511336ms","start":"2026-09-11T17:27:54.102663Z","end":"2026-09-11T17:27:54.240174Z","steps":["trace[162169645] 'process raft request' (duration: 137.262069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:27:54.753498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.994431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-11T17:27:54.753588Z","caller":"traceutil/trace.go:171","msg":"trace[2110341442] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1846; }","duration":"451.130845ms","start":"2026-09-11T17:27:54.302431Z","end":"2026-09-11T17:27:54.753562Z","steps":["trace[2110341442] 'range keys from in-memory index tree' (duration: 450.798587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:27:54.753901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:27:54.302413Z","time spent":"451.473076ms","remote":"127.0.0.1:34672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-11T17:27:54.753672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.162153ms","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-11T17:27:54.754047Z","caller":"traceutil/trace.go:171","msg":"trace[1771575335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"238.592504ms","start":"2026-09-11T17:27:54.515433Z","end":"2026-09-11T17:27:54.754025Z","steps":["trace[1771575335] 'range keys from in-memory index tree' (duration: 237.909105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:27:54.753731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.545346ms","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-09-11T17:27:54.754345Z","caller":"traceutil/trace.go:171","msg":"trace[1299338685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1846; }","duration":"315.204714ms","start":"2026-09-11T17:27:54.439124Z","end":"2026-09-11T17:27:54.754329Z","steps":["trace[1299338685] 'range keys from in-memory index tree' (duration: 314.36299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:27:54.754401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:27:54.439065Z","time spent":"315.319838ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-11T17:27:54.753735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.251409ms","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-11T17:27:54.754532Z","caller":"traceutil/trace.go:171","msg":"trace[288221896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1846; }","duration":"160.082152ms","start":"2026-09-11T17:27:54.594431Z","end":"2026-09-11T17:27:54.754513Z","steps":["trace[288221896] 'range keys from in-memory index tree' (duration: 159.044183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:27:54.753746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.019787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:27:54.755139Z","caller":"traceutil/trace.go:171","msg":"trace[197290993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1846; }","duration":"262.433707ms","start":"2026-09-11T17:27:54.492677Z","end":"2026-09-11T17:27:54.755111Z","steps":["trace[197290993] 'range keys from in-memory index tree' (duration: 260.938465ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:27:54.890494Z","caller":"traceutil/trace.go:171","msg":"trace[716251682] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"131.530087ms","start":"2026-09-11T17:27:54.758937Z","end":"2026-09-11T17:27:54.890467Z","steps":["trace[716251682] 'process raft request' (duration: 131.308281ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:27:54.890656Z","caller":"traceutil/trace.go:171","msg":"trace[1784121655] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"130.21944ms","start":"2026-09-11T17:27:54.760383Z","end":"2026-09-11T17:27:54.890602Z","steps":["trace[1784121655] 'process raft request' (duration: 130.003714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:28:02.817168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.764849ms","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-09-11T17:28:02.81726Z","caller":"traceutil/trace.go:171","msg":"trace[1979133187] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1942; }","duration":"161.877832ms","start":"2026-09-11T17:28:02.655362Z","end":"2026-09-11T17:28:02.81724Z","steps":["trace[1979133187] 'count revisions from in-memory index tree' (duration: 161.44873ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:29:30.667632Z","caller":"traceutil/trace.go:171","msg":"trace[1336922353] linearizableReadLoop","detail":"{readStateIndex:2754; appliedIndex:2753; }","duration":"247.888872ms","start":"2026-09-11T17:29:30.419717Z","end":"2026-09-11T17:29:30.667606Z","steps":["trace[1336922353] 'read index received' (duration: 220.97867ms)","trace[1336922353] 'applied index is now lower than readState.Index' (duration: 26.908922ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:29:30.66773Z","caller":"traceutil/trace.go:171","msg":"trace[518815011] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"272.621797ms","start":"2026-09-11T17:29:30.395073Z","end":"2026-09-11T17:29:30.667695Z","steps":["trace[518815011] 'process raft request' (duration: 245.587123ms)","trace[518815011] 'compare' (duration: 26.548356ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:29:30.667817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.071855ms","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-09-11T17:29:30.66785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.293932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:29:30.6679Z","caller":"traceutil/trace.go:171","msg":"trace[1970647103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2655; }","duration":"169.394513ms","start":"2026-09-11T17:29:30.498489Z","end":"2026-09-11T17:29:30.667884Z","steps":["trace[1970647103] 'agreement among raft nodes before linearized reading' (duration: 169.297182ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:29:30.667895Z","caller":"traceutil/trace.go:171","msg":"trace[1534075182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2655; }","duration":"248.184476ms","start":"2026-09-11T17:29:30.419689Z","end":"2026-09-11T17:29:30.667874Z","steps":["trace[1534075182] 'agreement among raft nodes before linearized reading' (duration: 248.065834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:29:55.809659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.286476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:29:55.810122Z","caller":"traceutil/trace.go:171","msg":"trace[923702760] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"208.9932ms","start":"2026-09-11T17:29:55.60111Z","end":"2026-09-11T17:29:55.810103Z","steps":["trace[923702760] 'process raft request' (duration: 208.902318ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:29:55.810477Z","caller":"traceutil/trace.go:171","msg":"trace[399403774] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"211.382587ms","start":"2026-09-11T17:29:55.59908Z","end":"2026-09-11T17:29:55.810463Z","steps":["trace[399403774] 'process raft request' (duration: 107.197997ms)","trace[399403774] 'compare' (duration: 103.013702ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:29:55.810677Z","caller":"traceutil/trace.go:171","msg":"trace[383462364] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"110.373727ms","start":"2026-09-11T17:29:55.700279Z","end":"2026-09-11T17:29:55.810652Z","steps":["trace[383462364] 'process raft request' (duration: 109.764968ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:29:55.811027Z","caller":"traceutil/trace.go:171","msg":"trace[1033068922] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"210.625365ms","start":"2026-09-11T17:29:55.600379Z","end":"2026-09-11T17:29:55.811005Z","steps":["trace[1033068922] 'process raft request' (duration: 209.467596ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:29:56.111246Z","caller":"traceutil/trace.go:171","msg":"trace[1145317142] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"119.522957ms","start":"2026-09-11T17:29:55.991701Z","end":"2026-09-11T17:29:56.111224Z","steps":["trace[1145317142] 'process raft request' (duration: 119.396945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:29:56.349567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.039737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:29:56.349661Z","caller":"traceutil/trace.go:171","msg":"trace[482125609] linearizableReadLoop","detail":"{readStateIndex:2941; appliedIndex:2940; }","duration":"154.603908ms","start":"2026-09-11T17:29:56.195041Z","end":"2026-09-11T17:29:56.349645Z","steps":["trace[482125609] 'read index received' (duration: 2.333737ms)","trace[482125609] 'applied index is now lower than readState.Index' (duration: 152.269111ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:29:56.349704Z","caller":"traceutil/trace.go:171","msg":"trace[402206450] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"223.900256ms","start":"2026-09-11T17:29:56.125774Z","end":"2026-09-11T17:29:56.349675Z","steps":["trace[402206450] 'process raft request' (duration: 71.667606ms)","trace[402206450] 'compare' (duration: 151.925556ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:29:56.349795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.75563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-11T17:29:56.349836Z","caller":"traceutil/trace.go:171","msg":"trace[747711669] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2831; }","duration":"154.822302ms","start":"2026-09-11T17:29:56.194992Z","end":"2026-09-11T17:29:56.349814Z","steps":["trace[747711669] 'agreement among raft nodes before linearized reading' (duration: 154.6928ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:25.913148Z","caller":"traceutil/trace.go:171","msg":"trace[697892321] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"102.530846ms","start":"2026-09-11T17:32:25.810594Z","end":"2026-09-11T17:32:25.913125Z","steps":["trace[697892321] 'process raft request' (duration: 101.914666ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:30.830188Z","caller":"traceutil/trace.go:171","msg":"trace[1623045548] linearizableReadLoop","detail":"{readStateIndex:4749; appliedIndex:4748; }","duration":"207.64688ms","start":"2026-09-11T17:32:30.622522Z","end":"2026-09-11T17:32:30.830169Z","steps":["trace[1623045548] 'read index received' (duration: 207.437674ms)","trace[1623045548] 'applied index is now lower than readState.Index' (duration: 208.016µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:30.830256Z","caller":"traceutil/trace.go:171","msg":"trace[2048624844] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"216.055284ms","start":"2026-09-11T17:32:30.614169Z","end":"2026-09-11T17:32:30.830224Z","steps":["trace[2048624844] 'process raft request' (duration: 215.871728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:30.830405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.866987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-09-11T17:32:30.830447Z","caller":"traceutil/trace.go:171","msg":"trace[833042028] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4550; }","duration":"207.9538ms","start":"2026-09-11T17:32:30.622482Z","end":"2026-09-11T17:32:30.830436Z","steps":["trace[833042028] 'agreement among raft nodes before linearized reading' (duration: 207.819215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:30.830578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.567424ms","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-11T17:32:30.830612Z","caller":"traceutil/trace.go:171","msg":"trace[2065522370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4550; }","duration":"190.616206ms","start":"2026-09-11T17:32:30.639986Z","end":"2026-09-11T17:32:30.830602Z","steps":["trace[2065522370] 'agreement among raft nodes before linearized reading' (duration: 190.461241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:30.830861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.328626ms","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-11T17:32:30.830901Z","caller":"traceutil/trace.go:171","msg":"trace[575189484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4550; }","duration":"182.390207ms","start":"2026-09-11T17:32:30.648501Z","end":"2026-09-11T17:32:30.830891Z","steps":["trace[575189484] 'agreement among raft nodes before linearized reading' (duration: 182.290734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.791357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.896301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:32:36.791639Z","caller":"traceutil/trace.go:171","msg":"trace[2123840557] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"340.830327ms","start":"2026-09-11T17:32:36.450776Z","end":"2026-09-11T17:32:36.791606Z","steps":["trace[2123840557] 'process raft request' (duration: 192.556424ms)","trace[2123840557] 'compare' (duration: 147.698185ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:36.791739Z","caller":"traceutil/trace.go:171","msg":"trace[213933503] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4834; }","duration":"337.512075ms","start":"2026-09-11T17:32:36.454207Z","end":"2026-09-11T17:32:36.791719Z","steps":["trace[213933503] 'read index received' (duration: 5.933063ms)","trace[213933503] 'applied index is now lower than readState.Index' (duration: 331.577952ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:32:36.791789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.450765Z","time spent":"340.94587ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:32:36.791846Z","caller":"traceutil/trace.go:171","msg":"trace[1190110753] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"333.274654ms","start":"2026-09-11T17:32:36.458548Z","end":"2026-09-11T17:32:36.791822Z","steps":["trace[1190110753] 'process raft request' (duration: 333.019667ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:36.791851Z","caller":"traceutil/trace.go:171","msg":"trace[767669893] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"327.456776ms","start":"2026-09-11T17:32:36.464377Z","end":"2026-09-11T17:32:36.791833Z","steps":["trace[767669893] 'process raft request' (duration: 327.304261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.791932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.464371Z","time spent":"327.526618ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T17:32:36.791948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.458539Z","time spent":"333.358507ms","remote":"127.0.0.1:48314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:32:36.791998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.706214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-keystone-peer-discovery\" ","response":"range_response_count:1 size:814"} {"level":"info","ts":"2026-09-11T17:32:36.792035Z","caller":"traceutil/trace.go:171","msg":"trace[1664974294] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-keystone-peer-discovery; range_end:; response_count:1; response_revision:4637; }","duration":"327.759725ms","start":"2026-09-11T17:32:36.464265Z","end":"2026-09-11T17:32:36.792025Z","steps":["trace[1664974294] 'agreement among raft nodes before linearized reading' (duration: 327.662562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.792064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.464255Z","time spent":"327.803477ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":837,"request content":"key:\"/registry/roles/openstack/rabbitmq-keystone-peer-discovery\" "} {"level":"warn","ts":"2026-09-11T17:32:36.792124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.397681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-09-11T17:32:36.792194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.047055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:32:36.792188Z","caller":"traceutil/trace.go:171","msg":"trace[856861561] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4637; }","duration":"285.484384ms","start":"2026-09-11T17:32:36.506687Z","end":"2026-09-11T17:32:36.792172Z","steps":["trace[856861561] 'agreement among raft nodes before linearized reading' (duration: 285.35682ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:36.792219Z","caller":"traceutil/trace.go:171","msg":"trace[267839364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4637; }","duration":"303.114446ms","start":"2026-09-11T17:32:36.489097Z","end":"2026-09-11T17:32:36.792212Z","steps":["trace[267839364] 'agreement among raft nodes before linearized reading' (duration: 303.073725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.792238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.48908Z","time spent":"303.153878ms","remote":"127.0.0.1:48102","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-09-11T17:32:36.791998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.782303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-11T17:32:36.79233Z","caller":"traceutil/trace.go:171","msg":"trace[1824691910] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4637; }","duration":"338.088152ms","start":"2026-09-11T17:32:36.454186Z","end":"2026-09-11T17:32:36.792274Z","steps":["trace[1824691910] 'agreement among raft nodes before linearized reading' (duration: 337.649849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.792376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.454179Z","time spent":"338.182175ms","remote":"127.0.0.1:48218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-09-11T17:32:36.792662Z","caller":"traceutil/trace.go:171","msg":"trace[1660036107] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"338.464265ms","start":"2026-09-11T17:32:36.454175Z","end":"2026-09-11T17:32:36.792639Z","steps":["trace[1660036107] 'process raft request' (duration: 337.304709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.792743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.777647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-09-11T17:32:36.792785Z","caller":"traceutil/trace.go:171","msg":"trace[783770931] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4637; }","duration":"172.846358ms","start":"2026-09-11T17:32:36.619929Z","end":"2026-09-11T17:32:36.792775Z","steps":["trace[783770931] 'agreement among raft nodes before linearized reading' (duration: 172.122155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:36.792839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:36.454159Z","time spent":"338.576058ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:32:37.186131Z","caller":"traceutil/trace.go:171","msg":"trace[1069186597] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4847; }","duration":"187.713368ms","start":"2026-09-11T17:32:36.9984Z","end":"2026-09-11T17:32:37.186113Z","steps":["trace[1069186597] 'read index received' (duration: 187.514151ms)","trace[1069186597] 'applied index is now lower than readState.Index' (duration: 198.226µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:37.18621Z","caller":"traceutil/trace.go:171","msg":"trace[382562430] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"228.748029ms","start":"2026-09-11T17:32:36.957455Z","end":"2026-09-11T17:32:37.186203Z","steps":["trace[382562430] 'process raft request' (duration: 228.512312ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:37.186568Z","caller":"traceutil/trace.go:171","msg":"trace[785606678] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"226.193061ms","start":"2026-09-11T17:32:36.960368Z","end":"2026-09-11T17:32:37.186561Z","steps":["trace[785606678] 'process raft request' (duration: 225.695426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.186721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.55447ms","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-11T17:32:37.186941Z","caller":"traceutil/trace.go:171","msg":"trace[279199941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4647; }","duration":"197.819888ms","start":"2026-09-11T17:32:36.989114Z","end":"2026-09-11T17:32:37.186934Z","steps":["trace[279199941] 'agreement among raft nodes before linearized reading' (duration: 197.530349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.186936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.317398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"warn","ts":"2026-09-11T17:32:37.186819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.703673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"warn","ts":"2026-09-11T17:32:37.186977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.133141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-11T17:32:37.186995Z","caller":"traceutil/trace.go:171","msg":"trace[561261514] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4647; }","duration":"221.888818ms","start":"2026-09-11T17:32:36.965098Z","end":"2026-09-11T17:32:37.186987Z","steps":["trace[561261514] 'agreement among raft nodes before linearized reading' (duration: 221.664691ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:37.186999Z","caller":"traceutil/trace.go:171","msg":"trace[1005256055] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4647; }","duration":"230.415051ms","start":"2026-09-11T17:32:36.956569Z","end":"2026-09-11T17:32:37.186984Z","steps":["trace[1005256055] 'agreement among raft nodes before linearized reading' (duration: 230.174674ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:37.187016Z","caller":"traceutil/trace.go:171","msg":"trace[2004422388] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4647; }","duration":"176.195453ms","start":"2026-09-11T17:32:37.010806Z","end":"2026-09-11T17:32:37.187001Z","steps":["trace[2004422388] 'agreement among raft nodes before linearized reading' (duration: 176.09355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.186838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.976226ms","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-11T17:32:37.187075Z","caller":"traceutil/trace.go:171","msg":"trace[2131723791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4647; }","duration":"229.225804ms","start":"2026-09-11T17:32:36.957846Z","end":"2026-09-11T17:32:37.187071Z","steps":["trace[2131723791] 'agreement among raft nodes before linearized reading' (duration: 228.925635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.186831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.148542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:32:37.187113Z","caller":"traceutil/trace.go:171","msg":"trace[907753300] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:0; response_revision:4647; }","duration":"229.454391ms","start":"2026-09-11T17:32:36.957651Z","end":"2026-09-11T17:32:37.187106Z","steps":["trace[907753300] 'agreement among raft nodes before linearized reading' (duration: 229.140291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.18687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.05565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-11T17:32:37.18719Z","caller":"traceutil/trace.go:171","msg":"trace[1803987932] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4647; }","duration":"204.37944ms","start":"2026-09-11T17:32:36.982787Z","end":"2026-09-11T17:32:37.187167Z","steps":["trace[1803987932] 'agreement among raft nodes before linearized reading' (duration: 204.04605ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:37.40024Z","caller":"traceutil/trace.go:171","msg":"trace[1356980910] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"206.927426ms","start":"2026-09-11T17:32:37.193277Z","end":"2026-09-11T17:32:37.400204Z","steps":["trace[1356980910] 'process raft request' (duration: 186.244791ms)","trace[1356980910] 'compare' (duration: 20.421597ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:37.400283Z","caller":"traceutil/trace.go:171","msg":"trace[674234742] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"200.52119ms","start":"2026-09-11T17:32:37.199734Z","end":"2026-09-11T17:32:37.400255Z","steps":["trace[674234742] 'process raft request' (duration: 200.313763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.400447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.524014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-11T17:32:37.400711Z","caller":"traceutil/trace.go:171","msg":"trace[1719499099] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4650; }","duration":"206.816852ms","start":"2026-09-11T17:32:37.193881Z","end":"2026-09-11T17:32:37.400697Z","steps":["trace[1719499099] 'agreement among raft nodes before linearized reading' (duration: 206.443071ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:37.400244Z","caller":"traceutil/trace.go:171","msg":"trace[1844607588] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4849; }","duration":"206.304526ms","start":"2026-09-11T17:32:37.193916Z","end":"2026-09-11T17:32:37.40022Z","steps":["trace[1844607588] 'read index received' (duration: 26.053279ms)","trace[1844607588] 'applied index is now lower than readState.Index' (duration: 180.250187ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:32:37.401022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.002347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-11T17:32:37.401059Z","caller":"traceutil/trace.go:171","msg":"trace[1916401528] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4650; }","duration":"206.07385ms","start":"2026-09-11T17:32:37.194976Z","end":"2026-09-11T17:32:37.40105Z","steps":["trace[1916401528] 'agreement among raft nodes before linearized reading' (duration: 205.929306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.401287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.092159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-11T17:32:37.401342Z","caller":"traceutil/trace.go:171","msg":"trace[1915659687] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4650; }","duration":"159.171852ms","start":"2026-09-11T17:32:37.242158Z","end":"2026-09-11T17:32:37.40133Z","steps":["trace[1915659687] 'agreement among raft nodes before linearized reading' (duration: 159.039617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.401507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.132431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-11T17:32:37.40154Z","caller":"traceutil/trace.go:171","msg":"trace[1637783397] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4650; }","duration":"205.190043ms","start":"2026-09-11T17:32:37.196342Z","end":"2026-09-11T17:32:37.401532Z","steps":["trace[1637783397] 'agreement among raft nodes before linearized reading' (duration: 205.10513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:37.401814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.789652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-11T17:32:37.401879Z","caller":"traceutil/trace.go:171","msg":"trace[1755884375] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4650; }","duration":"206.887955ms","start":"2026-09-11T17:32:37.194982Z","end":"2026-09-11T17:32:37.40187Z","steps":["trace[1755884375] 'agreement among raft nodes before linearized reading' (duration: 206.674239ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:49.050991Z","caller":"traceutil/trace.go:171","msg":"trace[1613483805] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5062; }","duration":"426.644249ms","start":"2026-09-11T17:32:48.624321Z","end":"2026-09-11T17:32:49.050966Z","steps":["trace[1613483805] 'read index received' (duration: 426.336ms)","trace[1613483805] 'applied index is now lower than readState.Index' (duration: 306.479µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:32:49.051056Z","caller":"traceutil/trace.go:171","msg":"trace[1538566133] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"473.104243ms","start":"2026-09-11T17:32:48.577911Z","end":"2026-09-11T17:32:49.051016Z","steps":["trace[1538566133] 'process raft request' (duration: 472.865516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:49.051296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.944827ms","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":"warn","ts":"2026-09-11T17:32:49.051356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.289007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-11T17:32:49.051407Z","caller":"traceutil/trace.go:171","msg":"trace[1139375661] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4851; }","duration":"427.120783ms","start":"2026-09-11T17:32:48.624263Z","end":"2026-09-11T17:32:49.051384Z","steps":["trace[1139375661] 'agreement among raft nodes before linearized reading' (duration: 426.840845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:49.051229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.514319ms","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":"warn","ts":"2026-09-11T17:32:49.051454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:48.624241Z","time spent":"427.202985ms","remote":"127.0.0.1:48278","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-keystone-server-0\" "} {"level":"info","ts":"2026-09-11T17:32:49.051449Z","caller":"traceutil/trace.go:171","msg":"trace[381825133] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4851; }","duration":"112.436491ms","start":"2026-09-11T17:32:48.93899Z","end":"2026-09-11T17:32:49.051427Z","steps":["trace[381825133] 'agreement among raft nodes before linearized reading' (duration: 112.190144ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:32:49.051528Z","caller":"traceutil/trace.go:171","msg":"trace[895626877] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4851; }","duration":"294.833949ms","start":"2026-09-11T17:32:48.756641Z","end":"2026-09-11T17:32:49.051475Z","steps":["trace[895626877] 'agreement among raft nodes before linearized reading' (duration: 294.513759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:49.051586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.317652ms","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-11T17:32:49.051771Z","caller":"traceutil/trace.go:171","msg":"trace[2101356054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4851; }","duration":"125.513288ms","start":"2026-09-11T17:32:48.926173Z","end":"2026-09-11T17:32:49.051686Z","steps":["trace[2101356054] 'agreement among raft nodes before linearized reading' (duration: 125.051334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:32:49.052748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:32:48.577892Z","time spent":"473.427993ms","remote":"127.0.0.1:48314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10794,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:33:26.729715Z","caller":"traceutil/trace.go:171","msg":"trace[601732384] linearizableReadLoop","detail":"{readStateIndex:5468; appliedIndex:5466; }","duration":"107.065198ms","start":"2026-09-11T17:33:26.622633Z","end":"2026-09-11T17:33:26.729698Z","steps":["trace[601732384] 'read index received' (duration: 3.0043ms)","trace[601732384] 'applied index is now lower than readState.Index' (duration: 104.060118ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:33:26.729908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.260073ms","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-11T17:33:26.729962Z","caller":"traceutil/trace.go:171","msg":"trace[1154096337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5241; }","duration":"107.349676ms","start":"2026-09-11T17:33:26.622602Z","end":"2026-09-11T17:33:26.729951Z","steps":["trace[1154096337] 'agreement among raft nodes before linearized reading' (duration: 107.215173ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:33:26.729882Z","caller":"traceutil/trace.go:171","msg":"trace[133514741] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"119.618941ms","start":"2026-09-11T17:33:26.610227Z","end":"2026-09-11T17:33:26.729846Z","steps":["trace[133514741] 'process raft request' (duration: 119.365575ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:35:23.930534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3016} {"level":"info","ts":"2026-09-11T17:35:24.048098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3016,"took":"116.315232ms","hash":2913963139,"current-db-size-bytes":58601472,"current-db-size":"59 MB","current-db-size-in-use-bytes":30789632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-11T17:35:24.048164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2913963139,"revision":3016,"compact-revision":-1} {"level":"info","ts":"2026-09-11T17:36:41.608065Z","caller":"traceutil/trace.go:171","msg":"trace[140386538] transaction","detail":"{read_only:false; response_revision:6887; number_of_response:1; }","duration":"146.71617ms","start":"2026-09-11T17:36:41.461327Z","end":"2026-09-11T17:36:41.608044Z","steps":["trace[140386538] 'process raft request' (duration: 146.565447ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:36:50.578325Z","caller":"traceutil/trace.go:171","msg":"trace[1816863943] transaction","detail":"{read_only:false; response_revision:6949; number_of_response:1; }","duration":"165.138019ms","start":"2026-09-11T17:36:50.413149Z","end":"2026-09-11T17:36:50.578287Z","steps":["trace[1816863943] 'process raft request' (duration: 75.891465ms)","trace[1816863943] 'compare' (duration: 89.025329ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:38:41.749359Z","caller":"traceutil/trace.go:171","msg":"trace[693932597] transaction","detail":"{read_only:false; response_revision:7691; number_of_response:1; }","duration":"118.306105ms","start":"2026-09-11T17:38:41.631033Z","end":"2026-09-11T17:38:41.749339Z","steps":["trace[693932597] 'process raft request' (duration: 109.077275ms)"],"step_count":1}