{"level":"warn","ts":"2026-06-10T12:55:29.261486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T12:55:29.261587Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-06-10T12:55:29.261657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T12:55:29.26167Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-06-10T12:55:29.261693Z","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-06-10T12:55:29.262378Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-06-10T12:55:29.262469Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-06-10T12:55:29.267215Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.513487ms"} {"level":"info","ts":"2026-06-10T12:55:29.278963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-06-10T12:55:29.279406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T12:55:29.279454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-06-10T12:55:29.27948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T12:55:29.279507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-06-10T12:55:29.27959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-06-10T12:55:29.289333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T12:55:29.292108Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T12:55:29.293527Z","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-06-10T12:55:29.297981Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T12:55:29.298257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T12:55:29.298386Z","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-06-10T12:55:29.2985Z","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-06-10T12:55:29.29852Z","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-06-10T12:55:29.302387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-06-10T12:55:29.302571Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-06-10T12:55:29.305093Z","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-06-10T12:55:29.305297Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-06-10T12:55:29.305393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-06-10T12:55:29.305599Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T12:55:29.305679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T12:55:29.480662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T12:55:29.480744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T12:55:29.48077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-06-10T12:55:29.480794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-06-10T12:55:29.480807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-06-10T12:55:29.480823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-06-10T12:55:29.480843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-06-10T12:55:29.481862Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T12:55:29.481912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T12:55:29.481998Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:29.481975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T12:55:29.482166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T12:55:29.482455Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T12:55:29.483635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:29.483749Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:29.483784Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:29.485058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-06-10T12:55:29.485455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T12:55:47.619964Z","caller":"traceutil/trace.go:171","msg":"trace[1660046375] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"122.498742ms","start":"2026-06-10T12:55:47.497444Z","end":"2026-06-10T12:55:47.619942Z","steps":["trace[1660046375] 'process raft request' (duration: 119.143767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:58.473533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.460691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T12:55:58.473822Z","caller":"traceutil/trace.go:171","msg":"trace[1156210571] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"454.709177ms","start":"2026-06-10T12:55:58.019068Z","end":"2026-06-10T12:55:58.473777Z","steps":["trace[1156210571] 'process raft request' (duration: 47.370751ms)","trace[1156210571] 'compare' (duration: 406.302917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:55:58.474026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:58.019051Z","time spent":"454.871621ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:00.431044Z","caller":"traceutil/trace.go:171","msg":"trace[146737254] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"114.947611ms","start":"2026-06-10T12:56:00.316066Z","end":"2026-06-10T12:56:00.431013Z","steps":["trace[146737254] 'read index received' (duration: 64.545975ms)","trace[146737254] 'applied index is now lower than readState.Index' (duration: 50.400126ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:56:00.431146Z","caller":"traceutil/trace.go:171","msg":"trace[1334539512] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"139.293174ms","start":"2026-06-10T12:56:00.2918Z","end":"2026-06-10T12:56:00.431094Z","steps":["trace[1334539512] 'process raft request' (duration: 88.794354ms)","trace[1334539512] 'compare' (duration: 50.278123ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:56:00.43141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.30198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-06-10T12:56:00.431718Z","caller":"traceutil/trace.go:171","msg":"trace[1157324741] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:435; }","duration":"115.677371ms","start":"2026-06-10T12:56:00.316013Z","end":"2026-06-10T12:56:00.43169Z","steps":["trace[1157324741] 'agreement among raft nodes before linearized reading' (duration: 115.132386ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:00.431434Z","caller":"traceutil/trace.go:171","msg":"trace[1318434578] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"112.774238ms","start":"2026-06-10T12:56:00.318626Z","end":"2026-06-10T12:56:00.4314Z","steps":["trace[1318434578] 'process raft request' (duration: 112.47216ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:15.653381Z","caller":"traceutil/trace.go:171","msg":"trace[689146927] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"343.168325ms","start":"2026-06-10T12:56:15.310189Z","end":"2026-06-10T12:56:15.653357Z","steps":["trace[689146927] 'process raft request' (duration: 343.065152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.65351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.310161Z","time spent":"343.290247ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.659842Z","caller":"traceutil/trace.go:171","msg":"trace[1305679572] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"345.658754ms","start":"2026-06-10T12:56:15.314152Z","end":"2026-06-10T12:56:15.659811Z","steps":["trace[1305679572] 'process raft request' (duration: 345.323395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.659914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.314123Z","time spent":"345.760056ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.660098Z","caller":"traceutil/trace.go:171","msg":"trace[1151844125] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"345.633655ms","start":"2026-06-10T12:56:15.314427Z","end":"2026-06-10T12:56:15.660061Z","steps":["trace[1151844125] 'process raft request' (duration: 345.267444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.660248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.31439Z","time spent":"345.798558ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.660885Z","caller":"traceutil/trace.go:171","msg":"trace[1766803067] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"335.971533ms","start":"2026-06-10T12:56:15.324899Z","end":"2026-06-10T12:56:15.660871Z","steps":["trace[1766803067] 'process raft request' (duration: 335.622423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.66098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.324872Z","time spent":"336.079907ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.661092Z","caller":"traceutil/trace.go:171","msg":"trace[243000157] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"332.910039ms","start":"2026-06-10T12:56:15.328171Z","end":"2026-06-10T12:56:15.661081Z","steps":["trace[243000157] 'process raft request' (duration: 332.560099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.661169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.328159Z","time spent":"332.985261ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.661392Z","caller":"traceutil/trace.go:171","msg":"trace[1551919563] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"334.03449ms","start":"2026-06-10T12:56:15.32712Z","end":"2026-06-10T12:56:15.661154Z","steps":["trace[1551919563] 'process raft request' (duration: 333.452664ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:15.661579Z","caller":"traceutil/trace.go:171","msg":"trace[384149743] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"334.332528ms","start":"2026-06-10T12:56:15.32719Z","end":"2026-06-10T12:56:15.661523Z","steps":["trace[384149743] 'process raft request' (duration: 333.498945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.661592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.327098Z","time spent":"334.39273ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T12:56:15.661676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.32716Z","time spent":"334.490232ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.661743Z","caller":"traceutil/trace.go:171","msg":"trace[60459742] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"333.106464ms","start":"2026-06-10T12:56:15.32862Z","end":"2026-06-10T12:56:15.661726Z","steps":["trace[60459742] 'process raft request' (duration: 332.159017ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:15.661852Z","caller":"traceutil/trace.go:171","msg":"trace[1903170137] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"175.995605ms","start":"2026-06-10T12:56:15.485836Z","end":"2026-06-10T12:56:15.661831Z","steps":["trace[1903170137] 'process raft request' (duration: 175.739739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.6619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.328606Z","time spent":"333.199557ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.662071Z","caller":"traceutil/trace.go:171","msg":"trace[185205435] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"127.07998ms","start":"2026-06-10T12:56:15.534971Z","end":"2026-06-10T12:56:15.662051Z","steps":["trace[185205435] 'process raft request' (duration: 126.683629ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:15.661648Z","caller":"traceutil/trace.go:171","msg":"trace[577823960] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"321.278403ms","start":"2026-06-10T12:56:15.340325Z","end":"2026-06-10T12:56:15.661603Z","steps":["trace[577823960] 'process raft request' (duration: 320.499282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.662203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.340288Z","time spent":"321.88805ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:15.662353Z","caller":"traceutil/trace.go:171","msg":"trace[26298079] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"285.950186ms","start":"2026-06-10T12:56:15.376395Z","end":"2026-06-10T12:56:15.662345Z","steps":["trace[26298079] 'process raft request' (duration: 285.02387ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:15.661959Z","caller":"traceutil/trace.go:171","msg":"trace[1287471613] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"321.424387ms","start":"2026-06-10T12:56:15.340523Z","end":"2026-06-10T12:56:15.661948Z","steps":["trace[1287471613] 'process raft request' (duration: 320.389658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:15.663735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:15.340503Z","time spent":"323.191657ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T12:56:22.18302Z","caller":"traceutil/trace.go:171","msg":"trace[1209310951] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"413.077887ms","start":"2026-06-10T12:56:21.769911Z","end":"2026-06-10T12:56:22.182989Z","steps":["trace[1209310951] 'process raft request' (duration: 412.857941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:22.183224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:21.769889Z","time spent":"413.235592ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:22.290613Z","caller":"traceutil/trace.go:171","msg":"trace[702692156] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"465.445219ms","start":"2026-06-10T12:56:21.825138Z","end":"2026-06-10T12:56:22.290584Z","steps":["trace[702692156] 'process raft request' (duration: 464.75181ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:22.290859Z","caller":"traceutil/trace.go:171","msg":"trace[1323261597] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"413.727185ms","start":"2026-06-10T12:56:21.877077Z","end":"2026-06-10T12:56:22.290804Z","steps":["trace[1323261597] 'read index received' (duration: 305.883893ms)","trace[1323261597] 'applied index is now lower than readState.Index' (duration: 107.840341ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:56:22.290914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:21.824693Z","time spent":"466.12755ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:22.291045Z","caller":"traceutil/trace.go:171","msg":"trace[1686442611] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"316.87102ms","start":"2026-06-10T12:56:21.974108Z","end":"2026-06-10T12:56:22.290979Z","steps":["trace[1686442611] 'process raft request' (duration: 316.426057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:22.291251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.151637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T12:56:22.291331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:21.97408Z","time spent":"317.063216ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:22.291367Z","caller":"traceutil/trace.go:171","msg":"trace[909832841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"414.326523ms","start":"2026-06-10T12:56:21.877025Z","end":"2026-06-10T12:56:22.291351Z","steps":["trace[909832841] 'agreement among raft nodes before linearized reading' (duration: 413.994583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:22.291411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:21.877008Z","time spent":"414.392455ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T12:56:22.923785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.912905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T12:56:22.92398Z","caller":"traceutil/trace.go:171","msg":"trace[904776502] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"627.105816ms","start":"2026-06-10T12:56:22.296857Z","end":"2026-06-10T12:56:22.923963Z","steps":["trace[904776502] 'process raft request' (duration: 627.047794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:22.924038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:22.296829Z","time spent":"627.185268ms","remote":"127.0.0.1:34878","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-06-10T12:56:22.924016Z","caller":"traceutil/trace.go:171","msg":"trace[289188497] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"627.117516ms","start":"2026-06-10T12:56:22.296857Z","end":"2026-06-10T12:56:22.923974Z","steps":["trace[289188497] 'process raft request' (duration: 238.918253ms)","trace[289188497] 'compare' (duration: 387.674158ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:56:22.924184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:22.296828Z","time spent":"627.25848ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:22.963759Z","caller":"traceutil/trace.go:171","msg":"trace[1199360099] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"600.576865ms","start":"2026-06-10T12:56:22.363121Z","end":"2026-06-10T12:56:22.963698Z","steps":["trace[1199360099] 'process raft request' (duration: 600.379189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:22.963979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:22.363081Z","time spent":"600.81092ms","remote":"127.0.0.1:35106","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-06-10T12:56:24.217716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118965555823931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T12:56:24.233677Z","caller":"traceutil/trace.go:171","msg":"trace[1086148694] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"516.737023ms","start":"2026-06-10T12:56:23.716907Z","end":"2026-06-10T12:56:24.233644Z","steps":["trace[1086148694] 'read index received' (duration: 515.501768ms)","trace[1086148694] 'applied index is now lower than readState.Index' (duration: 1.233895ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:56:24.233775Z","caller":"traceutil/trace.go:171","msg":"trace[1374090786] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"848.433447ms","start":"2026-06-10T12:56:23.385283Z","end":"2026-06-10T12:56:24.233717Z","steps":["trace[1374090786] 'process raft request' (duration: 847.313297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:24.233983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:23.385248Z","time spent":"848.621493ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:56:24.233953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.015001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T12:56:24.234075Z","caller":"traceutil/trace.go:171","msg":"trace[241106297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"517.146055ms","start":"2026-06-10T12:56:23.7169Z","end":"2026-06-10T12:56:24.234046Z","steps":["trace[241106297] 'agreement among raft nodes before linearized reading' (duration: 516.915648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:24.234029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.005205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T12:56:24.234158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:23.71684Z","time spent":"517.303248ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T12:56:24.234214Z","caller":"traceutil/trace.go:171","msg":"trace[1690078641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"138.213671ms","start":"2026-06-10T12:56:24.095977Z","end":"2026-06-10T12:56:24.234191Z","steps":["trace[1690078641] 'agreement among raft nodes before linearized reading' (duration: 137.921503ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:25.879063Z","caller":"traceutil/trace.go:171","msg":"trace[2027471881] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"100.822116ms","start":"2026-06-10T12:56:25.7782Z","end":"2026-06-10T12:56:25.879022Z","steps":["trace[2027471881] 'process raft request' (duration: 100.556658ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:25.879219Z","caller":"traceutil/trace.go:171","msg":"trace[866326459] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"105.28ms","start":"2026-06-10T12:56:25.773899Z","end":"2026-06-10T12:56:25.879179Z","steps":["trace[866326459] 'process raft request' (duration: 104.663494ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:26.213675Z","caller":"traceutil/trace.go:171","msg":"trace[1484104803] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"189.798871ms","start":"2026-06-10T12:56:26.023841Z","end":"2026-06-10T12:56:26.21364Z","steps":["trace[1484104803] 'process raft request' (duration: 189.605895ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:39.947341Z","caller":"traceutil/trace.go:171","msg":"trace[656238354] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"195.573253ms","start":"2026-06-10T12:56:39.751741Z","end":"2026-06-10T12:56:39.947314Z","steps":["trace[656238354] 'read index received' (duration: 195.455259ms)","trace[656238354] 'applied index is now lower than readState.Index' (duration: 116.804µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:56:39.947501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.731846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T12:56:39.947534Z","caller":"traceutil/trace.go:171","msg":"trace[146940758] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:787; }","duration":"195.812959ms","start":"2026-06-10T12:56:39.751712Z","end":"2026-06-10T12:56:39.947525Z","steps":["trace[146940758] 'agreement among raft nodes before linearized reading' (duration: 195.712866ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:39.947775Z","caller":"traceutil/trace.go:171","msg":"trace[1044696504] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"242.084532ms","start":"2026-06-10T12:56:39.705653Z","end":"2026-06-10T12:56:39.947738Z","steps":["trace[1044696504] 'process raft request' (duration: 241.480244ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:55.174922Z","caller":"traceutil/trace.go:171","msg":"trace[1942552329] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"144.042362ms","start":"2026-06-10T12:56:55.030851Z","end":"2026-06-10T12:56:55.174893Z","steps":["trace[1942552329] 'process raft request' (duration: 143.841467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:00.401837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.536383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T12:58:00.402142Z","caller":"traceutil/trace.go:171","msg":"trace[1201536831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"508.82695ms","start":"2026-06-10T12:57:59.8932Z","end":"2026-06-10T12:58:00.402027Z","steps":["trace[1201536831] 'range keys from in-memory index tree' (duration: 508.430569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:00.402145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.331497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-10T12:58:00.402222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:57:59.893177Z","time spent":"509.020156ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T12:58:00.402294Z","caller":"traceutil/trace.go:171","msg":"trace[356783243] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"206.261028ms","start":"2026-06-10T12:58:00.196011Z","end":"2026-06-10T12:58:00.402272Z","steps":["trace[356783243] 'read index received' (duration: 8.54827ms)","trace[356783243] 'applied index is now lower than readState.Index' (duration: 197.711338ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:00.402413Z","caller":"traceutil/trace.go:171","msg":"trace[887526815] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"400.308021ms","start":"2026-06-10T12:58:00.00209Z","end":"2026-06-10T12:58:00.402398Z","steps":["trace[887526815] 'process raft request' (duration: 202.594804ms)","trace[887526815] 'compare' (duration: 196.815853ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:00.402485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:00.002058Z","time spent":"400.392003ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:58:00.403565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.550994ms","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-06-10T12:58:00.403673Z","caller":"traceutil/trace.go:171","msg":"trace[873689415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"207.673486ms","start":"2026-06-10T12:58:00.19594Z","end":"2026-06-10T12:58:00.403614Z","steps":["trace[873689415] 'agreement among raft nodes before linearized reading' (duration: 206.746241ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:00.641533Z","caller":"traceutil/trace.go:171","msg":"trace[1713285013] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"214.651932ms","start":"2026-06-10T12:58:00.426857Z","end":"2026-06-10T12:58:00.641509Z","steps":["trace[1713285013] 'read index received' (duration: 157.033748ms)","trace[1713285013] 'applied index is now lower than readState.Index' (duration: 57.617114ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:00.641754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.896178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T12:58:00.641732Z","caller":"traceutil/trace.go:171","msg":"trace[854457069] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"229.299053ms","start":"2026-06-10T12:58:00.412361Z","end":"2026-06-10T12:58:00.64166Z","steps":["trace[854457069] 'process raft request' (duration: 171.507224ms)","trace[854457069] 'compare' (duration: 57.528221ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:00.641809Z","caller":"traceutil/trace.go:171","msg":"trace[643176941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"215.009712ms","start":"2026-06-10T12:58:00.42678Z","end":"2026-06-10T12:58:00.64179Z","steps":["trace[643176941] 'agreement among raft nodes before linearized reading' (duration: 214.827367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:01.052861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.751049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T12:58:01.052991Z","caller":"traceutil/trace.go:171","msg":"trace[2078168231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"252.951025ms","start":"2026-06-10T12:58:00.800015Z","end":"2026-06-10T12:58:01.052966Z","steps":["trace[2078168231] 'range keys from in-memory index tree' (duration: 252.555243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:07.963952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.75106ms","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-06-10T12:58:07.964117Z","caller":"traceutil/trace.go:171","msg":"trace[175910492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1959; }","duration":"111.987297ms","start":"2026-06-10T12:58:07.852097Z","end":"2026-06-10T12:58:07.964084Z","steps":["trace[175910492] 'range keys from in-memory index tree' (duration: 111.476982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:07.964407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.414072ms","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-06-10T12:58:07.964607Z","caller":"traceutil/trace.go:171","msg":"trace[1170591730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1959; }","duration":"150.69589ms","start":"2026-06-10T12:58:07.813876Z","end":"2026-06-10T12:58:07.964572Z","steps":["trace[1170591730] 'range keys from in-memory index tree' (duration: 149.803605ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:14.884338Z","caller":"traceutil/trace.go:171","msg":"trace[1953206779] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"365.652732ms","start":"2026-06-10T12:58:14.518656Z","end":"2026-06-10T12:58:14.884308Z","steps":["trace[1953206779] 'read index received' (duration: 365.61784ms)","trace[1953206779] 'applied index is now lower than readState.Index' (duration: 33.592µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:14.884473Z","caller":"traceutil/trace.go:171","msg":"trace[123674054] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"409.630193ms","start":"2026-06-10T12:58:14.474783Z","end":"2026-06-10T12:58:14.884413Z","steps":["trace[123674054] 'process raft request' (duration: 409.329584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:14.884688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.015382ms","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":"warn","ts":"2026-06-10T12:58:14.884734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:14.474755Z","time spent":"409.852229ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:58:14.884747Z","caller":"traceutil/trace.go:171","msg":"trace[1900319734] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2008; }","duration":"366.142625ms","start":"2026-06-10T12:58:14.518586Z","end":"2026-06-10T12:58:14.884729Z","steps":["trace[1900319734] 'agreement among raft nodes before linearized reading' (duration: 365.876128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:14.884836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:14.518538Z","time spent":"366.28805ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-10T12:58:14.890078Z","caller":"traceutil/trace.go:171","msg":"trace[972326707] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"186.346488ms","start":"2026-06-10T12:58:14.703703Z","end":"2026-06-10T12:58:14.89005Z","steps":["trace[972326707] 'process raft request' (duration: 186.129822ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:14.890667Z","caller":"traceutil/trace.go:171","msg":"trace[1831780994] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"172.80807ms","start":"2026-06-10T12:58:14.717746Z","end":"2026-06-10T12:58:14.890554Z","steps":["trace[1831780994] 'process raft request' (duration: 172.252913ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:45.192733Z","caller":"traceutil/trace.go:171","msg":"trace[418568164] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"193.131674ms","start":"2026-06-10T12:58:44.999576Z","end":"2026-06-10T12:58:45.192707Z","steps":["trace[418568164] 'process raft request' (duration: 192.862616ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:45.192702Z","caller":"traceutil/trace.go:171","msg":"trace[539211609] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"132.080836ms","start":"2026-06-10T12:58:45.060582Z","end":"2026-06-10T12:58:45.192663Z","steps":["trace[539211609] 'read index received' (duration: 131.669254ms)","trace[539211609] 'applied index is now lower than readState.Index' (duration: 409.982µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:45.19297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.363884ms","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-06-10T12:58:45.193066Z","caller":"traceutil/trace.go:171","msg":"trace[21852037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"132.537909ms","start":"2026-06-10T12:58:45.060513Z","end":"2026-06-10T12:58:45.193051Z","steps":["trace[21852037] 'agreement among raft nodes before linearized reading' (duration: 132.270582ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:45.842455Z","caller":"traceutil/trace.go:171","msg":"trace[1352679366] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"145.928453ms","start":"2026-06-10T12:58:45.696508Z","end":"2026-06-10T12:58:45.842436Z","steps":["trace[1352679366] 'process raft request' (duration: 145.772469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:51.528676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.372078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T12:58:51.528791Z","caller":"traceutil/trace.go:171","msg":"trace[1852085089] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2300; }","duration":"291.942028ms","start":"2026-06-10T12:58:51.236834Z","end":"2026-06-10T12:58:51.528776Z","steps":["trace[1852085089] 'read index received' (duration: 22.204561ms)","trace[1852085089] 'applied index is now lower than readState.Index' (duration: 269.736246ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:51.528846Z","caller":"traceutil/trace.go:171","msg":"trace[106325837] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"311.587369ms","start":"2026-06-10T12:58:51.217249Z","end":"2026-06-10T12:58:51.528837Z","steps":["trace[106325837] 'process raft request' (duration: 41.971295ms)","trace[106325837] 'compare' (duration: 269.166821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:51.528889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:51.21721Z","time spent":"311.657611ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:58:51.529092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.253276ms","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-06-10T12:58:51.52914Z","caller":"traceutil/trace.go:171","msg":"trace[603580157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2213; }","duration":"292.34855ms","start":"2026-06-10T12:58:51.236782Z","end":"2026-06-10T12:58:51.529131Z","steps":["trace[603580157] 'agreement among raft nodes before linearized reading' (duration: 292.193575ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:51.780464Z","caller":"traceutil/trace.go:171","msg":"trace[911655049] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"251.626811ms","start":"2026-06-10T12:58:51.528809Z","end":"2026-06-10T12:58:51.780436Z","steps":["trace[911655049] 'read index received' (duration: 241.996382ms)","trace[911655049] 'applied index is now lower than readState.Index' (duration: 9.629339ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:51.780702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.73901ms","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-06-10T12:58:51.780743Z","caller":"traceutil/trace.go:171","msg":"trace[1924131954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2214; }","duration":"540.842183ms","start":"2026-06-10T12:58:51.23989Z","end":"2026-06-10T12:58:51.780732Z","steps":["trace[1924131954] 'agreement among raft nodes before linearized reading' (duration: 540.652388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:51.780744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.308083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T12:58:51.7807Z","caller":"traceutil/trace.go:171","msg":"trace[935307426] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"409.757157ms","start":"2026-06-10T12:58:51.37089Z","end":"2026-06-10T12:58:51.780647Z","steps":["trace[935307426] 'process raft request' (duration: 400.028915ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:51.780782Z","caller":"traceutil/trace.go:171","msg":"trace[266630731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2214; }","duration":"380.408224ms","start":"2026-06-10T12:58:51.400363Z","end":"2026-06-10T12:58:51.780771Z","steps":["trace[266630731] 'agreement among raft nodes before linearized reading' (duration: 380.27885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:51.78078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:51.239869Z","time spent":"540.902835ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T12:58:51.780805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:51.40035Z","time spent":"380.449886ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T12:58:51.780938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:51.370851Z","time spent":"409.955462ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:58:52.477384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.450594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T12:58:52.477511Z","caller":"traceutil/trace.go:171","msg":"trace[738456573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2218; }","duration":"261.642981ms","start":"2026-06-10T12:58:52.215838Z","end":"2026-06-10T12:58:52.477481Z","steps":["trace[738456573] 'range keys from in-memory index tree' (duration: 261.179407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:52.47774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.602529ms","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-06-10T12:58:52.477939Z","caller":"traceutil/trace.go:171","msg":"trace[19983554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2218; }","duration":"261.830807ms","start":"2026-06-10T12:58:52.216082Z","end":"2026-06-10T12:58:52.477913Z","steps":["trace[19983554] 'range keys from in-memory index tree' (duration: 261.432165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:52.704242Z","caller":"traceutil/trace.go:171","msg":"trace[1050562773] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"222.029753ms","start":"2026-06-10T12:58:52.482186Z","end":"2026-06-10T12:58:52.704216Z","steps":["trace[1050562773] 'process raft request' (duration: 221.847528ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:52.727355Z","caller":"traceutil/trace.go:171","msg":"trace[1128343496] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2306; }","duration":"189.766019ms","start":"2026-06-10T12:58:52.537567Z","end":"2026-06-10T12:58:52.727333Z","steps":["trace[1128343496] 'read index received' (duration: 166.517189ms)","trace[1128343496] 'applied index is now lower than readState.Index' (duration: 23.24756ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:52.727446Z","caller":"traceutil/trace.go:171","msg":"trace[20188634] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"244.461131ms","start":"2026-06-10T12:58:52.482937Z","end":"2026-06-10T12:58:52.727398Z","steps":["trace[20188634] 'process raft request' (duration: 244.239974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:52.727634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.059959ms","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":"warn","ts":"2026-06-10T12:58:52.727633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.633093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T12:58:52.727693Z","caller":"traceutil/trace.go:171","msg":"trace[2123166893] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2220; }","duration":"190.147661ms","start":"2026-06-10T12:58:52.537529Z","end":"2026-06-10T12:58:52.727677Z","steps":["trace[2123166893] 'agreement among raft nodes before linearized reading' (duration: 189.919585ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:52.727693Z","caller":"traceutil/trace.go:171","msg":"trace[1248204567] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2220; }","duration":"161.768217ms","start":"2026-06-10T12:58:52.565909Z","end":"2026-06-10T12:58:52.727677Z","steps":["trace[1248204567] 'agreement among raft nodes before linearized reading' (duration: 161.641223ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:58.064436Z","caller":"traceutil/trace.go:171","msg":"trace[86771112] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2333; }","duration":"241.415476ms","start":"2026-06-10T12:58:57.822985Z","end":"2026-06-10T12:58:58.0644Z","steps":["trace[86771112] 'read index received' (duration: 241.321733ms)","trace[86771112] 'applied index is now lower than readState.Index' (duration: 92.433µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:58.064503Z","caller":"traceutil/trace.go:171","msg":"trace[919791082] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"262.572117ms","start":"2026-06-10T12:58:57.801902Z","end":"2026-06-10T12:58:58.064474Z","steps":["trace[919791082] 'process raft request' (duration: 262.299349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:58.218903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.843377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T12:58:58.219048Z","caller":"traceutil/trace.go:171","msg":"trace[246476166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2245; }","duration":"396.081683ms","start":"2026-06-10T12:58:57.822924Z","end":"2026-06-10T12:58:58.219005Z","steps":["trace[246476166] 'agreement among raft nodes before linearized reading' (duration: 241.660432ms)","trace[246476166] 'range keys from in-memory index tree' (duration: 154.02566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:58.219553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:57.822894Z","time spent":"396.638809ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T12:58:58.219646Z","caller":"traceutil/trace.go:171","msg":"trace[699458978] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"290.640212ms","start":"2026-06-10T12:58:57.928984Z","end":"2026-06-10T12:58:58.219624Z","steps":["trace[699458978] 'process raft request' (duration: 237.33971ms)","trace[699458978] 'compare' (duration: 52.852159ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:58:58.281493Z","caller":"traceutil/trace.go:171","msg":"trace[546830105] linearizableReadLoop","detail":"{readStateIndex:2336; appliedIndex:2334; }","duration":"216.92069ms","start":"2026-06-10T12:58:58.064543Z","end":"2026-06-10T12:58:58.281464Z","steps":["trace[546830105] 'read index received' (duration: 101.696947ms)","trace[546830105] 'applied index is now lower than readState.Index' (duration: 115.222473ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:58.281673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.349828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7b9a776362050\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-10T12:58:58.281787Z","caller":"traceutil/trace.go:171","msg":"trace[1332533768] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b7b9a776362050; range_end:; response_count:1; response_revision:2246; }","duration":"407.515273ms","start":"2026-06-10T12:58:57.874247Z","end":"2026-06-10T12:58:58.281762Z","steps":["trace[1332533768] 'agreement among raft nodes before linearized reading' (duration: 407.272146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:58.281796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.298402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-10T12:58:58.281845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:57.874225Z","time spent":"407.610446ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7b9a776362050\" "} {"level":"info","ts":"2026-06-10T12:58:58.281863Z","caller":"traceutil/trace.go:171","msg":"trace[19300595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2246; }","duration":"449.412385ms","start":"2026-06-10T12:58:57.832435Z","end":"2026-06-10T12:58:58.281847Z","steps":["trace[19300595] 'agreement among raft nodes before linearized reading' (duration: 449.168209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:58.282033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:57.832421Z","time spent":"449.59789ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T12:58:58.281766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.413223ms","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-06-10T12:58:58.282115Z","caller":"traceutil/trace.go:171","msg":"trace[832474794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2246; }","duration":"307.845184ms","start":"2026-06-10T12:58:57.974247Z","end":"2026-06-10T12:58:58.282092Z","steps":["trace[832474794] 'agreement among raft nodes before linearized reading' (duration: 307.257488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:58.282171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:57.974214Z","time spent":"307.940176ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T12:59:48.032509Z","caller":"traceutil/trace.go:171","msg":"trace[1133416843] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"266.356237ms","start":"2026-06-10T12:59:47.766122Z","end":"2026-06-10T12:59:48.032478Z","steps":["trace[1133416843] 'process raft request' (duration: 266.198223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:00:20.192978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.912587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-10T13:00:20.193084Z","caller":"traceutil/trace.go:171","msg":"trace[610591788] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2880; }","duration":"520.074011ms","start":"2026-06-10T13:00:19.672987Z","end":"2026-06-10T13:00:20.193061Z","steps":["trace[610591788] 'range keys from in-memory index tree' (duration: 519.705252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:00:20.19313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:00:19.672964Z","time spent":"520.154763ms","remote":"127.0.0.1:34752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-10T13:00:20.193202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.641536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-10T13:00:20.193216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.631573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-10T13:00:20.193291Z","caller":"traceutil/trace.go:171","msg":"trace[641637673] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2880; }","duration":"554.758199ms","start":"2026-06-10T13:00:19.638512Z","end":"2026-06-10T13:00:20.19327Z","steps":["trace[641637673] 'count revisions from in-memory index tree' (duration: 554.510923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:00:20.193343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:00:19.638494Z","time spent":"554.837051ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-10T13:00:20.193333Z","caller":"traceutil/trace.go:171","msg":"trace[427890450] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2880; }","duration":"498.797737ms","start":"2026-06-10T13:00:19.694505Z","end":"2026-06-10T13:00:20.193303Z","steps":["trace[427890450] 'range keys from in-memory index tree' (duration: 498.415426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:00:20.193398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:00:19.694481Z","time spent":"498.90199ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-10T13:00:25.863474Z","caller":"traceutil/trace.go:171","msg":"trace[2142528281] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"303.639888ms","start":"2026-06-10T13:00:25.559411Z","end":"2026-06-10T13:00:25.863051Z","steps":["trace[2142528281] 'process raft request' (duration: 303.451173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:00:25.863632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:00:25.55938Z","time spent":"304.168463ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:02:35.712677Z","caller":"traceutil/trace.go:171","msg":"trace[165943830] transaction","detail":"{read_only:false; response_revision:3717; number_of_response:1; }","duration":"149.808751ms","start":"2026-06-10T13:02:35.562831Z","end":"2026-06-10T13:02:35.71264Z","steps":["trace[165943830] 'process raft request' (duration: 149.587105ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:02:35.718375Z","caller":"traceutil/trace.go:171","msg":"trace[995928347] transaction","detail":"{read_only:false; response_revision:3718; number_of_response:1; }","duration":"155.412758ms","start":"2026-06-10T13:02:35.562926Z","end":"2026-06-10T13:02:35.718339Z","steps":["trace[995928347] 'process raft request' (duration: 155.066479ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:03:08.661677Z","caller":"traceutil/trace.go:171","msg":"trace[79739010] linearizableReadLoop","detail":"{readStateIndex:4124; appliedIndex:4123; }","duration":"212.179272ms","start":"2026-06-10T13:03:08.449471Z","end":"2026-06-10T13:03:08.66165Z","steps":["trace[79739010] 'read index received' (duration: 211.926295ms)","trace[79739010] 'applied index is now lower than readState.Index' (duration: 252.047µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:03:08.661713Z","caller":"traceutil/trace.go:171","msg":"trace[1402504636] transaction","detail":"{read_only:false; response_revision:3969; number_of_response:1; }","duration":"260.526722ms","start":"2026-06-10T13:03:08.401162Z","end":"2026-06-10T13:03:08.661689Z","steps":["trace[1402504636] 'process raft request' (duration: 260.287286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:03:08.661906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.425051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:03:08.661946Z","caller":"traceutil/trace.go:171","msg":"trace[973122395] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:3969; }","duration":"212.519192ms","start":"2026-06-10T13:03:08.449417Z","end":"2026-06-10T13:03:08.661937Z","steps":["trace[973122395] 'agreement among raft nodes before linearized reading' (duration: 212.341027ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:03:08.835065Z","caller":"traceutil/trace.go:171","msg":"trace[901254178] linearizableReadLoop","detail":"{readStateIndex:4125; appliedIndex:4124; }","duration":"168.449143ms","start":"2026-06-10T13:03:08.666568Z","end":"2026-06-10T13:03:08.835017Z","steps":["trace[901254178] 'read index received' (duration: 162.131796ms)","trace[901254178] 'applied index is now lower than readState.Index' (duration: 6.315497ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:03:08.835214Z","caller":"traceutil/trace.go:171","msg":"trace[2045030116] transaction","detail":"{read_only:false; response_revision:3970; number_of_response:1; }","duration":"300.832297ms","start":"2026-06-10T13:03:08.534362Z","end":"2026-06-10T13:03:08.835195Z","steps":["trace[2045030116] 'process raft request' (duration: 294.408929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:03:08.835385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:03:08.534341Z","time spent":"300.91004ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:03:08.835819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.955787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:63167"} {"level":"info","ts":"2026-06-10T13:03:08.835882Z","caller":"traceutil/trace.go:171","msg":"trace[1779753770] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3970; }","duration":"169.356677ms","start":"2026-06-10T13:03:08.666506Z","end":"2026-06-10T13:03:08.835863Z","steps":["trace[1779753770] 'agreement among raft nodes before linearized reading' (duration: 168.646938ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.324164Z","caller":"traceutil/trace.go:171","msg":"trace[1316532356] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5125; }","duration":"395.581054ms","start":"2026-06-10T13:04:33.92855Z","end":"2026-06-10T13:04:34.324131Z","steps":["trace[1316532356] 'read index received' (duration: 342.844502ms)","trace[1316532356] 'applied index is now lower than readState.Index' (duration: 52.735262ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:34.324336Z","caller":"traceutil/trace.go:171","msg":"trace[1131886433] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"405.42357ms","start":"2026-06-10T13:04:33.918899Z","end":"2026-06-10T13:04:34.324323Z","steps":["trace[1131886433] 'process raft request' (duration: 352.566494ms)","trace[1131886433] 'compare' (duration: 52.048623ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:34.324474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:33.918854Z","time spent":"405.512172ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:04:34.324742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.77535ms","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-06-10T13:04:34.324836Z","caller":"traceutil/trace.go:171","msg":"trace[980681636] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4930; }","duration":"395.907963ms","start":"2026-06-10T13:04:33.928908Z","end":"2026-06-10T13:04:34.324816Z","steps":["trace[980681636] 'agreement among raft nodes before linearized reading' (duration: 395.682617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.324887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:33.928901Z","time spent":"395.976805ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-06-10T13:04:34.324946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.397397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:34.324993Z","caller":"traceutil/trace.go:171","msg":"trace[1590087638] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4930; }","duration":"396.48163ms","start":"2026-06-10T13:04:33.928498Z","end":"2026-06-10T13:04:34.32498Z","steps":["trace[1590087638] 'agreement among raft nodes before linearized reading' (duration: 396.412428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.325016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:33.928475Z","time spent":"396.535311ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"warn","ts":"2026-06-10T13:04:34.325139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.06669ms","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-06-10T13:04:34.325181Z","caller":"traceutil/trace.go:171","msg":"trace[1851608614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4930; }","duration":"198.150472ms","start":"2026-06-10T13:04:34.12702Z","end":"2026-06-10T13:04:34.325171Z","steps":["trace[1851608614] 'agreement among raft nodes before linearized reading' (duration: 198.05263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.325372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.332015ms","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-06-10T13:04:34.325407Z","caller":"traceutil/trace.go:171","msg":"trace[1067756559] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4930; }","duration":"343.413808ms","start":"2026-06-10T13:04:33.981981Z","end":"2026-06-10T13:04:34.325395Z","steps":["trace[1067756559] 'agreement among raft nodes before linearized reading' (duration: 343.268254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.325435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:33.981957Z","time spent":"343.472359ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-10T13:04:39.599201Z","caller":"traceutil/trace.go:171","msg":"trace[1194304206] linearizableReadLoop","detail":"{readStateIndex:5277; appliedIndex:5276; }","duration":"224.378244ms","start":"2026-06-10T13:04:39.374793Z","end":"2026-06-10T13:04:39.599171Z","steps":["trace[1194304206] 'read index received' (duration: 190.55492ms)","trace[1194304206] 'applied index is now lower than readState.Index' (duration: 33.822604ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:39.599545Z","caller":"traceutil/trace.go:171","msg":"trace[1476259746] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"278.078694ms","start":"2026-06-10T13:04:39.321453Z","end":"2026-06-10T13:04:39.599532Z","steps":["trace[1476259746] 'process raft request' (duration: 243.876079ms)","trace[1476259746] 'compare' (duration: 33.733151ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:39.599841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.021702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:04:39.599946Z","caller":"traceutil/trace.go:171","msg":"trace[471485946] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:5076; }","duration":"225.201057ms","start":"2026-06-10T13:04:39.374722Z","end":"2026-06-10T13:04:39.599923Z","steps":["trace[471485946] 'agreement among raft nodes before linearized reading' (duration: 224.850707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:39.956973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.295227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:04:39.957156Z","caller":"traceutil/trace.go:171","msg":"trace[893796083] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"464.063526ms","start":"2026-06-10T13:04:39.493078Z","end":"2026-06-10T13:04:39.957141Z","steps":["trace[893796083] 'process raft request' (duration: 463.998995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:39.95721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.492987Z","time spent":"464.19778ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:04:39.957602Z","caller":"traceutil/trace.go:171","msg":"trace[982938668] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"514.982178ms","start":"2026-06-10T13:04:39.442612Z","end":"2026-06-10T13:04:39.957594Z","steps":["trace[982938668] 'process raft request' (duration: 291.986082ms)","trace[982938668] 'compare' (duration: 222.149752ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:39.957652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.442579Z","time spent":"515.049811ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14481,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:04:39.97062Z","caller":"traceutil/trace.go:171","msg":"trace[1242022565] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5277; }","duration":"371.291706ms","start":"2026-06-10T13:04:39.599292Z","end":"2026-06-10T13:04:39.970584Z","steps":["trace[1242022565] 'read index received' (duration: 135.326938ms)","trace[1242022565] 'applied index is now lower than readState.Index' (duration: 235.963538ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:39.970724Z","caller":"traceutil/trace.go:171","msg":"trace[1849023044] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"371.482642ms","start":"2026-06-10T13:04:39.599178Z","end":"2026-06-10T13:04:39.97066Z","steps":["trace[1849023044] 'process raft request' (duration: 371.178983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:39.970865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.032178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8sspm\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-06-10T13:04:39.970964Z","caller":"traceutil/trace.go:171","msg":"trace[1556664540] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8sspm; range_end:; response_count:1; response_revision:5079; }","duration":"434.146791ms","start":"2026-06-10T13:04:39.53679Z","end":"2026-06-10T13:04:39.970937Z","steps":["trace[1556664540] 'agreement among raft nodes before linearized reading' (duration: 433.859523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:39.971002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.536713Z","time spent":"434.281795ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5171,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8sspm\" "} {"level":"warn","ts":"2026-06-10T13:04:39.970995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.599144Z","time spent":"371.724169ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:04:39.971146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.057298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17128"} {"level":"info","ts":"2026-06-10T13:04:39.971207Z","caller":"traceutil/trace.go:171","msg":"trace[266549014] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5079; }","duration":"578.146052ms","start":"2026-06-10T13:04:39.393044Z","end":"2026-06-10T13:04:39.97119Z","steps":["trace[266549014] 'agreement among raft nodes before linearized reading' (duration: 577.71194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:39.97131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.393031Z","time spent":"578.262115ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17151,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-10T13:04:40.021571Z","caller":"traceutil/trace.go:171","msg":"trace[1609199923] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"258.439386ms","start":"2026-06-10T13:04:39.763082Z","end":"2026-06-10T13:04:40.021522Z","steps":["trace[1609199923] 'process raft request' (duration: 258.125947ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:40.022284Z","caller":"traceutil/trace.go:171","msg":"trace[537054629] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"109.352074ms","start":"2026-06-10T13:04:39.912884Z","end":"2026-06-10T13:04:40.022236Z","steps":["trace[537054629] 'process raft request' (duration: 108.53883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:40.022491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.583163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:40.022631Z","caller":"traceutil/trace.go:171","msg":"trace[2022529333] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5082; }","duration":"237.788399ms","start":"2026-06-10T13:04:39.78481Z","end":"2026-06-10T13:04:40.022598Z","steps":["trace[2022529333] 'agreement among raft nodes before linearized reading' (duration: 237.47815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:40.02253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.882968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-06-10T13:04:40.022715Z","caller":"traceutil/trace.go:171","msg":"trace[1523311397] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5082; }","duration":"350.125885ms","start":"2026-06-10T13:04:39.672566Z","end":"2026-06-10T13:04:40.022692Z","steps":["trace[1523311397] 'agreement among raft nodes before linearized reading' (duration: 349.745845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:40.022756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.729731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62987"} {"level":"warn","ts":"2026-06-10T13:04:40.022788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.672539Z","time spent":"350.214298ms","remote":"127.0.0.1:34752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-06-10T13:04:40.02285Z","caller":"traceutil/trace.go:171","msg":"trace[284132891] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5082; }","duration":"418.824234ms","start":"2026-06-10T13:04:39.604Z","end":"2026-06-10T13:04:40.022825Z","steps":["trace[284132891] 'agreement among raft nodes before linearized reading' (duration: 418.373931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:40.022904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:39.60396Z","time spent":"418.928486ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":63010,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-10T13:04:42.492153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.406724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:04:42.492311Z","caller":"traceutil/trace.go:171","msg":"trace[2050718497] linearizableReadLoop","detail":"{readStateIndex:5408; appliedIndex:5407; }","duration":"553.569045ms","start":"2026-06-10T13:04:41.938704Z","end":"2026-06-10T13:04:42.492273Z","steps":["trace[2050718497] 'read index received' (duration: 193.891504ms)","trace[2050718497] 'applied index is now lower than readState.Index' (duration: 359.675951ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:42.492337Z","caller":"traceutil/trace.go:171","msg":"trace[569320893] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"556.62055ms","start":"2026-06-10T13:04:41.935668Z","end":"2026-06-10T13:04:42.492289Z","steps":["trace[569320893] 'process raft request' (duration: 196.98388ms)","trace[569320893] 'compare' (duration: 359.25679ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:42.492518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:41.93564Z","time spent":"556.794045ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T13:04:42.492592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.858383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz\" ","response":"range_response_count:1 size:9871"} {"level":"info","ts":"2026-06-10T13:04:42.492702Z","caller":"traceutil/trace.go:171","msg":"trace[953447121] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz; range_end:; response_count:1; response_revision:5204; }","duration":"554.053869ms","start":"2026-06-10T13:04:41.938622Z","end":"2026-06-10T13:04:42.492676Z","steps":["trace[953447121] 'agreement among raft nodes before linearized reading' (duration: 553.738341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.492746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:41.938591Z","time spent":"554.147502ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9894,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz\" "} {"level":"info","ts":"2026-06-10T13:04:42.575661Z","caller":"traceutil/trace.go:171","msg":"trace[1617788036] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"611.555704ms","start":"2026-06-10T13:04:41.964043Z","end":"2026-06-10T13:04:42.575599Z","steps":["trace[1617788036] 'process raft request' (duration: 611.120672ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:42.575842Z","caller":"traceutil/trace.go:171","msg":"trace[1530613677] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"448.700147ms","start":"2026-06-10T13:04:42.127109Z","end":"2026-06-10T13:04:42.575809Z","steps":["trace[1530613677] 'process raft request' (duration: 448.412049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.575942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:41.964015Z","time spent":"611.786051ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:04:42.575952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.675694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:42.576075Z","caller":"traceutil/trace.go:171","msg":"trace[1820177776] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5207; }","duration":"142.855448ms","start":"2026-06-10T13:04:42.433197Z","end":"2026-06-10T13:04:42.576052Z","steps":["trace[1820177776] 'agreement among raft nodes before linearized reading' (duration: 142.5622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.575996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:42.127078Z","time spent":"448.861242ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T13:04:42.575684Z","caller":"traceutil/trace.go:171","msg":"trace[265354321] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"541.875558ms","start":"2026-06-10T13:04:42.033765Z","end":"2026-06-10T13:04:42.57564Z","steps":["trace[265354321] 'process raft request' (duration: 541.622121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.576502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.463138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz.18b7b9fea101e2b5\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-10T13:04:42.576588Z","caller":"traceutil/trace.go:171","msg":"trace[869202336] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz.18b7b9fea101e2b5; range_end:; response_count:1; response_revision:5207; }","duration":"637.608912ms","start":"2026-06-10T13:04:41.938953Z","end":"2026-06-10T13:04:42.576562Z","steps":["trace[869202336] 'agreement among raft nodes before linearized reading' (duration: 637.402376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.576646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:41.938922Z","time spent":"637.710375ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":985,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p6lz.18b7b9fea101e2b5\" "} {"level":"warn","ts":"2026-06-10T13:04:42.576597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:42.033749Z","time spent":"542.65905ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:04:42.576923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.247356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728891"} {"level":"info","ts":"2026-06-10T13:04:42.577041Z","caller":"traceutil/trace.go:171","msg":"trace[1058924709] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5207; }","duration":"577.449962ms","start":"2026-06-10T13:04:41.999564Z","end":"2026-06-10T13:04:42.577014Z","steps":["trace[1058924709] 'agreement among raft nodes before linearized reading' (duration: 576.700791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.57712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:41.999535Z","time spent":"577.565895ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":728914,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-06-10T13:04:42.577385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.567231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-10T13:04:42.57656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.428169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:04:42.57751Z","caller":"traceutil/trace.go:171","msg":"trace[1219819425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5207; }","duration":"151.738696ms","start":"2026-06-10T13:04:42.425741Z","end":"2026-06-10T13:04:42.57748Z","steps":["trace[1219819425] 'agreement among raft nodes before linearized reading' (duration: 150.019058ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:42.577541Z","caller":"traceutil/trace.go:171","msg":"trace[970798383] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:5207; }","duration":"546.475897ms","start":"2026-06-10T13:04:42.031043Z","end":"2026-06-10T13:04:42.577519Z","steps":["trace[970798383] 'agreement among raft nodes before linearized reading' (duration: 545.339645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.5776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:42.031014Z","time spent":"546.567929ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-sl8v5\" "} {"level":"info","ts":"2026-06-10T13:04:42.631141Z","caller":"traceutil/trace.go:171","msg":"trace[751752594] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"125.557036ms","start":"2026-06-10T13:04:42.505527Z","end":"2026-06-10T13:04:42.631084Z","steps":["trace[751752594] 'process raft request' (duration: 125.271068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:42.631218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.459312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-10T13:04:42.631342Z","caller":"traceutil/trace.go:171","msg":"trace[529326088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341; range_end:; response_count:1; response_revision:5208; }","duration":"135.635027ms","start":"2026-06-10T13:04:42.495685Z","end":"2026-06-10T13:04:42.63132Z","steps":["trace[529326088] 'agreement among raft nodes before linearized reading' (duration: 135.336329ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:44.693153Z","caller":"traceutil/trace.go:171","msg":"trace[1122314659] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"145.920474ms","start":"2026-06-10T13:04:44.547198Z","end":"2026-06-10T13:04:44.693118Z","steps":["trace[1122314659] 'process raft request' (duration: 145.727988ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:44.805974Z","caller":"traceutil/trace.go:171","msg":"trace[2124543202] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"223.317575ms","start":"2026-06-10T13:04:44.58261Z","end":"2026-06-10T13:04:44.805927Z","steps":["trace[2124543202] 'process raft request' (duration: 208.973775ms)","trace[2124543202] 'compare' (duration: 14.102544ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:44.806211Z","caller":"traceutil/trace.go:171","msg":"trace[1105001390] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5445; }","duration":"219.590481ms","start":"2026-06-10T13:04:44.586569Z","end":"2026-06-10T13:04:44.80616Z","steps":["trace[1105001390] 'read index received' (duration: 106.493464ms)","trace[1105001390] 'applied index is now lower than readState.Index' (duration: 113.092827ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:44.806322Z","caller":"traceutil/trace.go:171","msg":"trace[1437885521] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"222.557293ms","start":"2026-06-10T13:04:44.583749Z","end":"2026-06-10T13:04:44.806307Z","steps":["trace[1437885521] 'process raft request' (duration: 222.118651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.806577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.990361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-06-10T13:04:44.806622Z","caller":"traceutil/trace.go:171","msg":"trace[1545956557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341; range_end:; response_count:1; response_revision:5242; }","duration":"220.097606ms","start":"2026-06-10T13:04:44.586508Z","end":"2026-06-10T13:04:44.806606Z","steps":["trace[1545956557] 'agreement among raft nodes before linearized reading' (duration: 219.869729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.806632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.6837ms","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-06-10T13:04:44.806678Z","caller":"traceutil/trace.go:171","msg":"trace[1701244603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5242; }","duration":"163.811524ms","start":"2026-06-10T13:04:44.64285Z","end":"2026-06-10T13:04:44.806661Z","steps":["trace[1701244603] 'agreement among raft nodes before linearized reading' (duration: 163.615448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.806922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.22111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-10T13:04:44.807023Z","caller":"traceutil/trace.go:171","msg":"trace[633204193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5242; }","duration":"157.398215ms","start":"2026-06-10T13:04:44.649601Z","end":"2026-06-10T13:04:44.807Z","steps":["trace[633204193] 'agreement among raft nodes before linearized reading' (duration: 157.142517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.807228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.105171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-06-10T13:04:44.807341Z","caller":"traceutil/trace.go:171","msg":"trace[953471422] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341; range_end:; response_count:1; response_revision:5242; }","duration":"159.260047ms","start":"2026-06-10T13:04:44.648062Z","end":"2026-06-10T13:04:44.807322Z","steps":["trace[953471422] 'agreement among raft nodes before linearized reading' (duration: 158.938698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.807427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.483103ms","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-06-10T13:04:44.807477Z","caller":"traceutil/trace.go:171","msg":"trace[1711702094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5242; }","duration":"164.587615ms","start":"2026-06-10T13:04:44.642877Z","end":"2026-06-10T13:04:44.807464Z","steps":["trace[1711702094] 'agreement among raft nodes before linearized reading' (duration: 164.475682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:44.80762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.840657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:04:44.807651Z","caller":"traceutil/trace.go:171","msg":"trace[690168958] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:5242; }","duration":"161.922761ms","start":"2026-06-10T13:04:44.645718Z","end":"2026-06-10T13:04:44.807641Z","steps":["trace[690168958] 'agreement among raft nodes before linearized reading' (duration: 161.829198ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:56.977397Z","caller":"traceutil/trace.go:171","msg":"trace[1948496604] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5680; }","duration":"100.964149ms","start":"2026-06-10T13:04:56.876394Z","end":"2026-06-10T13:04:56.977358Z","steps":["trace[1948496604] 'read index received' (duration: 66.621161ms)","trace[1948496604] 'applied index is now lower than readState.Index' (duration: 34.341478ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:56.977582Z","caller":"traceutil/trace.go:171","msg":"trace[774572658] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"111.044061ms","start":"2026-06-10T13:04:56.866516Z","end":"2026-06-10T13:04:56.97756Z","steps":["trace[774572658] 'process raft request' (duration: 110.575088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:56.977969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.561446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:56.978036Z","caller":"traceutil/trace.go:171","msg":"trace[1919585260] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5470; }","duration":"101.67198ms","start":"2026-06-10T13:04:56.876347Z","end":"2026-06-10T13:04:56.978019Z","steps":["trace[1919585260] 'agreement among raft nodes before linearized reading' (duration: 101.573047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:57.476038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.252421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:04:57.47653Z","caller":"traceutil/trace.go:171","msg":"trace[2130841053] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5693; }","duration":"154.861505ms","start":"2026-06-10T13:04:57.321633Z","end":"2026-06-10T13:04:57.476495Z","steps":["trace[2130841053] 'read index received' (duration: 10.968107ms)","trace[2130841053] 'applied index is now lower than readState.Index' (duration: 143.891348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:57.47676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.110582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T13:04:57.476823Z","caller":"traceutil/trace.go:171","msg":"trace[1090671171] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5485; }","duration":"155.178944ms","start":"2026-06-10T13:04:57.321625Z","end":"2026-06-10T13:04:57.476804Z","steps":["trace[1090671171] 'agreement among raft nodes before linearized reading' (duration: 154.972518ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:57.476983Z","caller":"traceutil/trace.go:171","msg":"trace[1872460503] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"254.275371ms","start":"2026-06-10T13:04:57.222689Z","end":"2026-06-10T13:04:57.476964Z","steps":["trace[1872460503] 'process raft request' (duration: 253.499839ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:57.477136Z","caller":"traceutil/trace.go:171","msg":"trace[127663125] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"254.420495ms","start":"2026-06-10T13:04:57.222695Z","end":"2026-06-10T13:04:57.477115Z","steps":["trace[127663125] 'process raft request' (duration: 253.596012ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:57.477219Z","caller":"traceutil/trace.go:171","msg":"trace[1194338058] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"178.199687ms","start":"2026-06-10T13:04:57.29898Z","end":"2026-06-10T13:04:57.47718Z","steps":["trace[1194338058] 'process raft request' (duration: 177.411945ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:57.477145Z","caller":"traceutil/trace.go:171","msg":"trace[855949349] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"254.523368ms","start":"2026-06-10T13:04:57.222604Z","end":"2026-06-10T13:04:57.477127Z","steps":["trace[855949349] 'process raft request' (duration: 110.028752ms)","trace[855949349] 'compare' (duration: 143.018775ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:58.02944Z","caller":"traceutil/trace.go:171","msg":"trace[1265034592] linearizableReadLoop","detail":"{readStateIndex:5698; appliedIndex:5697; }","duration":"159.589287ms","start":"2026-06-10T13:04:57.86982Z","end":"2026-06-10T13:04:58.029409Z","steps":["trace[1265034592] 'read index received' (duration: 102.539675ms)","trace[1265034592] 'applied index is now lower than readState.Index' (duration: 57.048852ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:58.029477Z","caller":"traceutil/trace.go:171","msg":"trace[881902694] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"352.68199ms","start":"2026-06-10T13:04:57.676755Z","end":"2026-06-10T13:04:58.029437Z","steps":["trace[881902694] 'process raft request' (duration: 295.704809ms)","trace[881902694] 'compare' (duration: 56.829986ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:58.029614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.781983ms","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-06-10T13:04:58.02964Z","caller":"traceutil/trace.go:171","msg":"trace[451468901] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5486; }","duration":"159.881065ms","start":"2026-06-10T13:04:57.869752Z","end":"2026-06-10T13:04:58.029633Z","steps":["trace[451468901] 'agreement among raft nodes before linearized reading' (duration: 159.798743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:58.02974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:57.676723Z","time spent":"352.916627ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:04:58.029784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.742149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:58.029892Z","caller":"traceutil/trace.go:171","msg":"trace[1215169033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5486; }","duration":"136.917305ms","start":"2026-06-10T13:04:57.892946Z","end":"2026-06-10T13:04:58.029863Z","steps":["trace[1215169033] 'agreement among raft nodes before linearized reading' (duration: 136.7433ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:59.119878Z","caller":"traceutil/trace.go:171","msg":"trace[2043693844] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"134.000662ms","start":"2026-06-10T13:04:58.985833Z","end":"2026-06-10T13:04:59.119833Z","steps":["trace[2043693844] 'process raft request' (duration: 133.629222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:05.828783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.546481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-10T13:05:05.828903Z","caller":"traceutil/trace.go:171","msg":"trace[88313328] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5652; }","duration":"340.732036ms","start":"2026-06-10T13:05:05.488138Z","end":"2026-06-10T13:05:05.82887Z","steps":["trace[88313328] 'range keys from in-memory index tree' (duration: 340.249952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:05.828979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:05.488106Z","time spent":"340.85609ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-10T13:05:05.829105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.948373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:05:05.829252Z","caller":"traceutil/trace.go:171","msg":"trace[142460485] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"313.090345ms","start":"2026-06-10T13:05:05.516136Z","end":"2026-06-10T13:05:05.829226Z","steps":["trace[142460485] 'process raft request' (duration: 155.908675ms)","trace[142460485] 'compare' (duration: 156.793889ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:05:05.829328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:05.516101Z","time spent":"313.196487ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:05:05.829342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.764311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-10T13:05:05.829478Z","caller":"traceutil/trace.go:171","msg":"trace[1876832400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b; range_end:; response_count:1; response_revision:5652; }","duration":"339.005778ms","start":"2026-06-10T13:05:05.49045Z","end":"2026-06-10T13:05:05.829456Z","steps":["trace[1876832400] 'range keys from in-memory index tree' (duration: 337.97623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:05.82954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:05.490423Z","time spent":"339.107171ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b\" "} {"level":"warn","ts":"2026-06-10T13:05:06.314384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.475178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:05:06.314535Z","caller":"traceutil/trace.go:171","msg":"trace[2016540936] linearizableReadLoop","detail":"{readStateIndex:5871; appliedIndex:5870; }","duration":"420.862374ms","start":"2026-06-10T13:05:05.893644Z","end":"2026-06-10T13:05:06.314506Z","steps":["trace[2016540936] 'read index received' (duration: 297.030896ms)","trace[2016540936] 'applied index is now lower than readState.Index' (duration: 123.829457ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:05:06.315186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.520182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:05:06.315288Z","caller":"traceutil/trace.go:171","msg":"trace[1245525357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5654; }","duration":"421.659666ms","start":"2026-06-10T13:05:05.893593Z","end":"2026-06-10T13:05:06.315252Z","steps":["trace[1245525357] 'agreement among raft nodes before linearized reading' (duration: 421.503562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.315326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:05.893566Z","time spent":"421.753918ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T13:05:06.314839Z","caller":"traceutil/trace.go:171","msg":"trace[400157928] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"479.464461ms","start":"2026-06-10T13:05:05.835352Z","end":"2026-06-10T13:05:06.314816Z","steps":["trace[400157928] 'process raft request' (duration: 355.416618ms)","trace[400157928] 'compare' (duration: 123.066966ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:05:06.315503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:05.83532Z","time spent":"480.124209ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:05:06.581058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.620525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:05:06.581191Z","caller":"traceutil/trace.go:171","msg":"trace[180553827] linearizableReadLoop","detail":"{readStateIndex:5872; appliedIndex:5871; }","duration":"266.586345ms","start":"2026-06-10T13:05:06.314584Z","end":"2026-06-10T13:05:06.58117Z","steps":["trace[180553827] 'read index received' (duration: 138.767806ms)","trace[180553827] 'applied index is now lower than readState.Index' (duration: 127.816939ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:05:06.581277Z","caller":"traceutil/trace.go:171","msg":"trace[767179611] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"524.340383ms","start":"2026-06-10T13:05:06.056923Z","end":"2026-06-10T13:05:06.581263Z","steps":["trace[767179611] 'process raft request' (duration: 396.38132ms)","trace[767179611] 'compare' (duration: 127.46284ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:05:06.58135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.056885Z","time spent":"524.431606ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:05:06.581831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.54379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-10T13:05:06.581894Z","caller":"traceutil/trace.go:171","msg":"trace[1297176637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b; range_end:; response_count:1; response_revision:5655; }","duration":"355.667763ms","start":"2026-06-10T13:05:06.226212Z","end":"2026-06-10T13:05:06.58188Z","steps":["trace[1297176637] 'agreement among raft nodes before linearized reading' (duration: 355.439786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.581931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.226187Z","time spent":"355.736256ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21a1b3e9-e894-485a-ae9c-46a7bf65eb7b\" "} {"level":"warn","ts":"2026-06-10T13:05:06.582266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.516507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-10T13:05:06.582507Z","caller":"traceutil/trace.go:171","msg":"trace[2096199344] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5655; }","duration":"346.698672ms","start":"2026-06-10T13:05:06.235682Z","end":"2026-06-10T13:05:06.582381Z","steps":["trace[2096199344] 'agreement among raft nodes before linearized reading' (duration: 345.706205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.582593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.235675Z","time spent":"346.898948ms","remote":"127.0.0.1:34752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-06-10T13:05:06.75462Z","caller":"traceutil/trace.go:171","msg":"trace[1973436798] linearizableReadLoop","detail":"{readStateIndex:5873; appliedIndex:5872; }","duration":"173.367593ms","start":"2026-06-10T13:05:06.581222Z","end":"2026-06-10T13:05:06.754589Z","steps":["trace[1973436798] 'read index received' (duration: 167.005695ms)","trace[1973436798] 'applied index is now lower than readState.Index' (duration: 6.360368ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:05:06.754667Z","caller":"traceutil/trace.go:171","msg":"trace[734009707] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"375.049184ms","start":"2026-06-10T13:05:06.379586Z","end":"2026-06-10T13:05:06.754635Z","steps":["trace[734009707] 'process raft request' (duration: 368.672506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.754889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.379536Z","time spent":"375.234131ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19054,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:05:06.754917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.379285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T13:05:06.754967Z","caller":"traceutil/trace.go:171","msg":"trace[1233349664] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5656; }","duration":"413.520728ms","start":"2026-06-10T13:05:06.341434Z","end":"2026-06-10T13:05:06.754954Z","steps":["trace[1233349664] 'agreement among raft nodes before linearized reading' (duration: 413.311553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.754967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.430694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:05:06.755009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.341408Z","time spent":"413.589231ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":99,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-06-10T13:05:06.755029Z","caller":"traceutil/trace.go:171","msg":"trace[491533725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5656; }","duration":"438.532437ms","start":"2026-06-10T13:05:06.316476Z","end":"2026-06-10T13:05:06.755008Z","steps":["trace[491533725] 'agreement among raft nodes before linearized reading' (duration: 438.420694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.755062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:05:06.316446Z","time spent":"438.608979ms","remote":"127.0.0.1:34596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T13:05:06.761964Z","caller":"traceutil/trace.go:171","msg":"trace[1817927752] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"122.788609ms","start":"2026-06-10T13:05:06.639144Z","end":"2026-06-10T13:05:06.761933Z","steps":["trace[1817927752] 'process raft request' (duration: 122.706067ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:05:06.76301Z","caller":"traceutil/trace.go:171","msg":"trace[979779923] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"170.251545ms","start":"2026-06-10T13:05:06.592741Z","end":"2026-06-10T13:05:06.762992Z","steps":["trace[979779923] 'process raft request' (duration: 168.880546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:06.763868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.568129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-06-10T13:05:06.763952Z","caller":"traceutil/trace.go:171","msg":"trace[311523901] range","detail":"{range_begin:/registry/persistentvolumes/pvc-55a9c187-0ea4-467b-a8aa-f07a75be4341; range_end:; response_count:1; response_revision:5658; }","duration":"177.661662ms","start":"2026-06-10T13:05:06.586266Z","end":"2026-06-10T13:05:06.763927Z","steps":["trace[311523901] 'agreement among raft nodes before linearized reading' (duration: 177.462137ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:05:29.53929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2934} {"level":"info","ts":"2026-06-10T13:05:29.613216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2934,"took":"71.62159ms","hash":1397039989,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T13:05:29.61338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1397039989,"revision":2934,"compact-revision":-1} {"level":"warn","ts":"2026-06-10T13:05:57.707253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.563158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-06-10T13:05:57.707334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.341438ms","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-06-10T13:05:57.707442Z","caller":"traceutil/trace.go:171","msg":"trace[722149718] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:6146; }","duration":"105.785664ms","start":"2026-06-10T13:05:57.601627Z","end":"2026-06-10T13:05:57.707413Z","steps":["trace[722149718] 'agreement among raft nodes before linearized reading' (duration: 105.427504ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:05:57.707465Z","caller":"traceutil/trace.go:171","msg":"trace[446373727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6146; }","duration":"121.540964ms","start":"2026-06-10T13:05:57.585896Z","end":"2026-06-10T13:05:57.707437Z","steps":["trace[446373727] 'agreement among raft nodes before linearized reading' (duration: 121.134592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:05:57.70748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.699376ms","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-06-10T13:05:57.707619Z","caller":"traceutil/trace.go:171","msg":"trace[918213293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6146; }","duration":"113.881901ms","start":"2026-06-10T13:05:57.593712Z","end":"2026-06-10T13:05:57.707594Z","steps":["trace[918213293] 'agreement among raft nodes before linearized reading' (duration: 113.531191ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:10:29.545657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5883} {"level":"info","ts":"2026-06-10T13:10:29.631373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5883,"took":"83.46865ms","hash":2064059415,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":26505216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-10T13:10:29.631444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2064059415,"revision":5883,"compact-revision":2934} {"level":"info","ts":"2026-06-10T13:11:12.291261Z","caller":"traceutil/trace.go:171","msg":"trace[1938675339] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"105.26544ms","start":"2026-06-10T13:11:12.185968Z","end":"2026-06-10T13:11:12.291234Z","steps":["trace[1938675339] 'process raft request' (duration: 105.071454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.67871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.308374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:11:14.678811Z","caller":"traceutil/trace.go:171","msg":"trace[1724793280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8488; }","duration":"787.451229ms","start":"2026-06-10T13:11:13.891335Z","end":"2026-06-10T13:11:14.678786Z","steps":["trace[1724793280] 'range keys from in-memory index tree' (duration: 787.220203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.678851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:13.891314Z","time spent":"787.528031ms","remote":"127.0.0.1:34580","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-06-10T13:11:14.678922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0285063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:11:14.679053Z","caller":"traceutil/trace.go:171","msg":"trace[1678724586] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8488; }","duration":"1.028703574s","start":"2026-06-10T13:11:13.650321Z","end":"2026-06-10T13:11:14.679024Z","steps":["trace[1678724586] 'count revisions from in-memory index tree' (duration: 1.028397546s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.679118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:13.650295Z","time spent":"1.028804108s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-10T13:11:14.679537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.444463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-06-10T13:11:14.679561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.594323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:11:14.67961Z","caller":"traceutil/trace.go:171","msg":"trace[945524043] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:8488; }","duration":"944.559596ms","start":"2026-06-10T13:11:13.73503Z","end":"2026-06-10T13:11:14.67959Z","steps":["trace[945524043] 'range keys from in-memory index tree' (duration: 944.112133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.679661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:13.735005Z","time spent":"944.643337ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-sl8v5\" "} {"level":"info","ts":"2026-06-10T13:11:14.679774Z","caller":"traceutil/trace.go:171","msg":"trace[953601769] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"382.349146ms","start":"2026-06-10T13:11:14.29741Z","end":"2026-06-10T13:11:14.67976Z","steps":["trace[953601769] 'process raft request' (duration: 382.248683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.679838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:14.297374Z","time spent":"382.429719ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:11:14.680047Z","caller":"traceutil/trace.go:171","msg":"trace[998963062] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"621.412762ms","start":"2026-06-10T13:11:14.058621Z","end":"2026-06-10T13:11:14.680034Z","steps":["trace[998963062] 'process raft request' (duration: 180.233542ms)","trace[998963062] 'compare' (duration: 440.127721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:11:14.680101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:14.058593Z","time spent":"621.478324ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:11:14.680414Z","caller":"traceutil/trace.go:171","msg":"trace[758834834] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8796; }","duration":"468.363788ms","start":"2026-06-10T13:11:14.212036Z","end":"2026-06-10T13:11:14.6804Z","steps":["trace[758834834] 'read index received' (duration: 26.831819ms)","trace[758834834] 'applied index is now lower than readState.Index' (duration: 441.530469ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:11:14.680818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.746779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-bbc4997d-nbft4.18b7ba57deabfe2e\" ","response":"range_response_count:1 size:1129"} {"level":"info","ts":"2026-06-10T13:11:14.680997Z","caller":"traceutil/trace.go:171","msg":"trace[1433220929] range","detail":"{range_begin:/registry/events/openstack/barbican-api-bbc4997d-nbft4.18b7ba57deabfe2e; range_end:; response_count:1; response_revision:8490; }","duration":"468.972565ms","start":"2026-06-10T13:11:14.211992Z","end":"2026-06-10T13:11:14.680965Z","steps":["trace[1433220929] 'agreement among raft nodes before linearized reading' (duration: 468.600735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.681058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:14.211973Z","time spent":"469.070827ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1152,"request content":"key:\"/registry/events/openstack/barbican-api-bbc4997d-nbft4.18b7ba57deabfe2e\" "} {"level":"info","ts":"2026-06-10T13:11:14.683823Z","caller":"traceutil/trace.go:171","msg":"trace[256931326] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"332.819244ms","start":"2026-06-10T13:11:14.35096Z","end":"2026-06-10T13:11:14.68378Z","steps":["trace[256931326] 'process raft request' (duration: 332.641779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:14.684013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:14.350921Z","time spent":"333.002999ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19392,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:11:24.923129Z","caller":"traceutil/trace.go:171","msg":"trace[271431971] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"177.444084ms","start":"2026-06-10T13:11:24.745658Z","end":"2026-06-10T13:11:24.923103Z","steps":["trace[271431971] 'process raft request' (duration: 174.216914ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:24.923117Z","caller":"traceutil/trace.go:171","msg":"trace[923608506] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8854; }","duration":"174.535363ms","start":"2026-06-10T13:11:24.748549Z","end":"2026-06-10T13:11:24.923084Z","steps":["trace[923608506] 'read index received' (duration: 31.594061ms)","trace[923608506] 'applied index is now lower than readState.Index' (duration: 142.940202ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:11:24.923412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.84104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:11:24.923465Z","caller":"traceutil/trace.go:171","msg":"trace[688647040] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:8545; }","duration":"174.941904ms","start":"2026-06-10T13:11:24.74851Z","end":"2026-06-10T13:11:24.923452Z","steps":["trace[688647040] 'agreement among raft nodes before linearized reading' (duration: 174.731848ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:25.606287Z","caller":"traceutil/trace.go:171","msg":"trace[1187024541] linearizableReadLoop","detail":"{readStateIndex:8859; appliedIndex:8858; }","duration":"160.652125ms","start":"2026-06-10T13:11:25.445606Z","end":"2026-06-10T13:11:25.606258Z","steps":["trace[1187024541] 'read index received' (duration: 160.328436ms)","trace[1187024541] 'applied index is now lower than readState.Index' (duration: 322.339µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:25.606416Z","caller":"traceutil/trace.go:171","msg":"trace[257303010] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"189.335276ms","start":"2026-06-10T13:11:25.417053Z","end":"2026-06-10T13:11:25.606389Z","steps":["trace[257303010] 'process raft request' (duration: 189.024328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:25.606641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.018305ms","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-06-10T13:11:25.606751Z","caller":"traceutil/trace.go:171","msg":"trace[1409402620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8549; }","duration":"161.16714ms","start":"2026-06-10T13:11:25.445561Z","end":"2026-06-10T13:11:25.606728Z","steps":["trace[1409402620] 'agreement among raft nodes before linearized reading' (duration: 160.895672ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:25.832217Z","caller":"traceutil/trace.go:171","msg":"trace[941721062] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"220.066935ms","start":"2026-06-10T13:11:25.612098Z","end":"2026-06-10T13:11:25.832165Z","steps":["trace[941721062] 'process raft request' (duration: 189.351837ms)","trace[941721062] 'compare' (duration: 30.221994ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:25.832338Z","caller":"traceutil/trace.go:171","msg":"trace[1250118292] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8859; }","duration":"142.272322ms","start":"2026-06-10T13:11:25.690041Z","end":"2026-06-10T13:11:25.832313Z","steps":["trace[1250118292] 'read index received' (duration: 111.427462ms)","trace[1250118292] 'applied index is now lower than readState.Index' (duration: 30.84388ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:11:25.83248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.426047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:11:25.832522Z","caller":"traceutil/trace.go:171","msg":"trace[1305682433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8553; }","duration":"142.514948ms","start":"2026-06-10T13:11:25.689997Z","end":"2026-06-10T13:11:25.832512Z","steps":["trace[1305682433] 'agreement among raft nodes before linearized reading' (duration: 142.405255ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:25.832587Z","caller":"traceutil/trace.go:171","msg":"trace[632603643] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"220.27116ms","start":"2026-06-10T13:11:25.612304Z","end":"2026-06-10T13:11:25.832575Z","steps":["trace[632603643] 'process raft request' (duration: 219.772736ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:25.832656Z","caller":"traceutil/trace.go:171","msg":"trace[832033706] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"219.663113ms","start":"2026-06-10T13:11:25.612942Z","end":"2026-06-10T13:11:25.832605Z","steps":["trace[832033706] 'process raft request' (duration: 219.271482ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:25.832884Z","caller":"traceutil/trace.go:171","msg":"trace[447016870] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"219.163178ms","start":"2026-06-10T13:11:25.613676Z","end":"2026-06-10T13:11:25.83284Z","steps":["trace[447016870] 'process raft request' (duration: 218.600763ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:52.022581Z","caller":"traceutil/trace.go:171","msg":"trace[1883108146] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"117.673286ms","start":"2026-06-10T13:11:51.904881Z","end":"2026-06-10T13:11:52.022555Z","steps":["trace[1883108146] 'process raft request' (duration: 117.331655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:59.308144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.630873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:11:59.308212Z","caller":"traceutil/trace.go:171","msg":"trace[1870433294] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:8783; }","duration":"142.735134ms","start":"2026-06-10T13:11:59.16546Z","end":"2026-06-10T13:11:59.308195Z","steps":["trace[1870433294] 'range keys from in-memory index tree' (duration: 142.484658ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:59.689941Z","caller":"traceutil/trace.go:171","msg":"trace[405836655] transaction","detail":"{read_only:false; response_revision:8784; number_of_response:1; }","duration":"115.403992ms","start":"2026-06-10T13:11:59.574504Z","end":"2026-06-10T13:11:59.689908Z","steps":["trace[405836655] 'process raft request' (duration: 115.192396ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.162087Z","caller":"traceutil/trace.go:171","msg":"trace[996144208] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"183.599966ms","start":"2026-06-10T13:12:08.978459Z","end":"2026-06-10T13:12:09.162059Z","steps":["trace[996144208] 'process raft request' (duration: 183.397211ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.1622Z","caller":"traceutil/trace.go:171","msg":"trace[1265973844] linearizableReadLoop","detail":"{readStateIndex:9198; appliedIndex:9197; }","duration":"124.504496ms","start":"2026-06-10T13:12:09.037667Z","end":"2026-06-10T13:12:09.162172Z","steps":["trace[1265973844] 'read index received' (duration: 124.245999ms)","trace[1265973844] 'applied index is now lower than readState.Index' (duration: 254.886µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:12:09.162476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.795995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s2zz8\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-10T13:12:09.162575Z","caller":"traceutil/trace.go:171","msg":"trace[903194146] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s2zz8; range_end:; response_count:1; response_revision:8875; }","duration":"124.942138ms","start":"2026-06-10T13:12:09.037613Z","end":"2026-06-10T13:12:09.162555Z","steps":["trace[903194146] 'agreement among raft nodes before linearized reading' (duration: 124.660249ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.307353Z","caller":"traceutil/trace.go:171","msg":"trace[69340897] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"174.294815ms","start":"2026-06-10T13:12:09.133033Z","end":"2026-06-10T13:12:09.307328Z","steps":["trace[69340897] 'process raft request' (duration: 174.218253ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.307701Z","caller":"traceutil/trace.go:171","msg":"trace[981910789] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"269.300759ms","start":"2026-06-10T13:12:09.038389Z","end":"2026-06-10T13:12:09.30769Z","steps":["trace[981910789] 'process raft request' (duration: 268.669201ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.308011Z","caller":"traceutil/trace.go:171","msg":"trace[1165740669] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"187.252567ms","start":"2026-06-10T13:12:09.120747Z","end":"2026-06-10T13:12:09.308Z","steps":["trace[1165740669] 'process raft request' (duration: 186.447775ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:09.329262Z","caller":"traceutil/trace.go:171","msg":"trace[700988928] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"159.72608ms","start":"2026-06-10T13:12:09.169503Z","end":"2026-06-10T13:12:09.329229Z","steps":["trace[700988928] 'process raft request' (duration: 159.471193ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:10.054462Z","caller":"traceutil/trace.go:171","msg":"trace[1612562172] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"107.512921ms","start":"2026-06-10T13:12:09.94692Z","end":"2026-06-10T13:12:10.054432Z","steps":["trace[1612562172] 'process raft request' (duration: 107.229343ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:12:11.323597Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002404.wal"} {"level":"info","ts":"2026-06-10T13:13:27.954571Z","caller":"traceutil/trace.go:171","msg":"trace[180164053] transaction","detail":"{read_only:false; response_revision:9637; number_of_response:1; }","duration":"345.902913ms","start":"2026-06-10T13:13:27.608633Z","end":"2026-06-10T13:13:27.954536Z","steps":["trace[180164053] 'process raft request' (duration: 315.917106ms)","trace[180164053] 'compare' (duration: 29.375701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:13:27.95474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:13:27.608617Z","time spent":"346.041447ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:13:27.954885Z","caller":"traceutil/trace.go:171","msg":"trace[860942366] transaction","detail":"{read_only:false; response_revision:9638; number_of_response:1; }","duration":"212.267169ms","start":"2026-06-10T13:13:27.742598Z","end":"2026-06-10T13:13:27.954865Z","steps":["trace[860942366] 'process raft request' (duration: 211.785816ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:13:30.62001Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T13:13:30.625819Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T13:13:30.625939Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T13:15:29.552794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8073} {"level":"info","ts":"2026-06-10T13:15:29.614868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8073,"took":"60.067928ms","hash":127683778,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":28499968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T13:15:29.614926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":127683778,"revision":8073,"compact-revision":5883} {"level":"info","ts":"2026-06-10T13:19:03.395189Z","caller":"traceutil/trace.go:171","msg":"trace[99413391] transaction","detail":"{read_only:false; response_revision:12055; number_of_response:1; }","duration":"161.436633ms","start":"2026-06-10T13:19:03.23357Z","end":"2026-06-10T13:19:03.395007Z","steps":["trace[99413391] 'process raft request' (duration: 94.572843ms)","trace[99413391] 'compare' (duration: 66.759307ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:19:03.432459Z","caller":"traceutil/trace.go:171","msg":"trace[1927895694] transaction","detail":"{read_only:false; response_revision:12056; number_of_response:1; }","duration":"118.76915ms","start":"2026-06-10T13:19:03.313658Z","end":"2026-06-10T13:19:03.432427Z","steps":["trace[1927895694] 'process raft request' (duration: 118.489332ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:19:04.678047Z","caller":"traceutil/trace.go:171","msg":"trace[259745522] transaction","detail":"{read_only:false; response_revision:12062; number_of_response:1; }","duration":"126.61854ms","start":"2026-06-10T13:19:04.551404Z","end":"2026-06-10T13:19:04.678022Z","steps":["trace[259745522] 'process raft request' (duration: 126.234259ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:20:29.558842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10484} {"level":"info","ts":"2026-06-10T13:20:29.64559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10484,"took":"84.949816ms","hash":866794674,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":25645056,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-10T13:20:29.645667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866794674,"revision":10484,"compact-revision":8073} {"level":"info","ts":"2026-06-10T13:24:56.767641Z","caller":"traceutil/trace.go:171","msg":"trace[1373961619] transaction","detail":"{read_only:false; response_revision:14465; number_of_response:1; }","duration":"107.94552ms","start":"2026-06-10T13:24:56.659672Z","end":"2026-06-10T13:24:56.767618Z","steps":["trace[1373961619] 'process raft request' (duration: 107.804345ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:22.341542Z","caller":"traceutil/trace.go:171","msg":"trace[1342252805] linearizableReadLoop","detail":"{readStateIndex:15112; appliedIndex:15111; }","duration":"151.180559ms","start":"2026-06-10T13:25:22.190329Z","end":"2026-06-10T13:25:22.341509Z","steps":["trace[1342252805] 'read index received' (duration: 135.071548ms)","trace[1342252805] 'applied index is now lower than readState.Index' (duration: 16.10671ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:25:22.341567Z","caller":"traceutil/trace.go:171","msg":"trace[1846273163] transaction","detail":"{read_only:false; response_revision:14598; number_of_response:1; }","duration":"162.430764ms","start":"2026-06-10T13:25:22.179085Z","end":"2026-06-10T13:25:22.341515Z","steps":["trace[1846273163] 'process raft request' (duration: 146.260932ms)","trace[1846273163] 'compare' (duration: 15.882793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:22.341817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.471046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-10T13:25:22.341957Z","caller":"traceutil/trace.go:171","msg":"trace[1409920885] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14598; }","duration":"151.567279ms","start":"2026-06-10T13:25:22.19028Z","end":"2026-06-10T13:25:22.341848Z","steps":["trace[1409920885] 'agreement among raft nodes before linearized reading' (duration: 151.388384ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:22.497154Z","caller":"traceutil/trace.go:171","msg":"trace[1256317091] transaction","detail":"{read_only:false; response_revision:14599; number_of_response:1; }","duration":"150.602604ms","start":"2026-06-10T13:25:22.346527Z","end":"2026-06-10T13:25:22.49713Z","steps":["trace[1256317091] 'process raft request' (duration: 150.057348ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:22.498338Z","caller":"traceutil/trace.go:171","msg":"trace[2068992211] transaction","detail":"{read_only:false; response_revision:14600; number_of_response:1; }","duration":"151.544159ms","start":"2026-06-10T13:25:22.346774Z","end":"2026-06-10T13:25:22.498319Z","steps":["trace[2068992211] 'process raft request' (duration: 151.398145ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:22.498547Z","caller":"traceutil/trace.go:171","msg":"trace[1869127777] transaction","detail":"{read_only:false; response_revision:14601; number_of_response:1; }","duration":"151.687913ms","start":"2026-06-10T13:25:22.346837Z","end":"2026-06-10T13:25:22.498525Z","steps":["trace[1869127777] 'process raft request' (duration: 151.439586ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:29.57471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12615} {"level":"info","ts":"2026-06-10T13:25:29.73089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12615,"took":"153.541535ms","hash":3278773614,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":26767360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-10T13:25:29.730972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3278773614,"revision":12615,"compact-revision":10484} {"level":"info","ts":"2026-06-10T13:25:37.861164Z","caller":"traceutil/trace.go:171","msg":"trace[1689914921] transaction","detail":"{read_only:false; response_revision:14691; number_of_response:1; }","duration":"124.44349ms","start":"2026-06-10T13:25:37.736694Z","end":"2026-06-10T13:25:37.861138Z","steps":["trace[1689914921] 'process raft request' (duration: 123.958047ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.308244Z","caller":"traceutil/trace.go:171","msg":"trace[148068454] transaction","detail":"{read_only:false; response_revision:14695; number_of_response:1; }","duration":"124.200805ms","start":"2026-06-10T13:25:38.184014Z","end":"2026-06-10T13:25:38.308214Z","steps":["trace[148068454] 'process raft request' (duration: 123.886086ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.308468Z","caller":"traceutil/trace.go:171","msg":"trace[2002452486] transaction","detail":"{read_only:false; response_revision:14696; number_of_response:1; }","duration":"124.442981ms","start":"2026-06-10T13:25:38.184015Z","end":"2026-06-10T13:25:38.308458Z","steps":["trace[2002452486] 'process raft request' (duration: 124.109232ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.399111Z","caller":"traceutil/trace.go:171","msg":"trace[42921719] transaction","detail":"{read_only:false; response_revision:14697; number_of_response:1; }","duration":"152.064733ms","start":"2026-06-10T13:25:38.247021Z","end":"2026-06-10T13:25:38.399086Z","steps":["trace[42921719] 'process raft request' (duration: 151.702794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.846569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.098307ms","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-06-10T13:25:38.846654Z","caller":"traceutil/trace.go:171","msg":"trace[666928595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14700; }","duration":"185.238711ms","start":"2026-06-10T13:25:38.661398Z","end":"2026-06-10T13:25:38.846636Z","steps":["trace[666928595] 'range keys from in-memory index tree' (duration: 184.899881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.846911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.992813ms","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-06-10T13:25:38.846986Z","caller":"traceutil/trace.go:171","msg":"trace[1250898754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14700; }","duration":"136.078915ms","start":"2026-06-10T13:25:38.710888Z","end":"2026-06-10T13:25:38.846967Z","steps":["trace[1250898754] 'range keys from in-memory index tree' (duration: 135.814439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.846977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.991113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T13:25:38.847032Z","caller":"traceutil/trace.go:171","msg":"trace[2016544798] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:14700; }","duration":"215.090877ms","start":"2026-06-10T13:25:38.631927Z","end":"2026-06-10T13:25:38.847018Z","steps":["trace[2016544798] 'count revisions from in-memory index tree' (duration: 214.912602ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.862081Z","caller":"traceutil/trace.go:171","msg":"trace[1262916340] transaction","detail":"{read_only:false; response_revision:14701; number_of_response:1; }","duration":"123.784232ms","start":"2026-06-10T13:25:38.738263Z","end":"2026-06-10T13:25:38.862047Z","steps":["trace[1262916340] 'process raft request' (duration: 123.585666ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.325047Z","caller":"traceutil/trace.go:171","msg":"trace[288352955] linearizableReadLoop","detail":"{readStateIndex:15226; appliedIndex:15225; }","duration":"183.768701ms","start":"2026-06-10T13:25:40.141256Z","end":"2026-06-10T13:25:40.325025Z","steps":["trace[288352955] 'read index received' (duration: 183.705929ms)","trace[288352955] 'applied index is now lower than readState.Index' (duration: 61.642µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:25:40.325149Z","caller":"traceutil/trace.go:171","msg":"trace[1698432400] transaction","detail":"{read_only:false; response_revision:14706; number_of_response:1; }","duration":"185.608592ms","start":"2026-06-10T13:25:40.139512Z","end":"2026-06-10T13:25:40.32512Z","steps":["trace[1698432400] 'process raft request' (duration: 185.267361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.325367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.09105ms","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-06-10T13:25:40.325416Z","caller":"traceutil/trace.go:171","msg":"trace[924244053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14706; }","duration":"184.186181ms","start":"2026-06-10T13:25:40.141217Z","end":"2026-06-10T13:25:40.325403Z","steps":["trace[924244053] 'agreement among raft nodes before linearized reading' (duration: 183.936965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.670254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.773228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:25:40.670725Z","caller":"traceutil/trace.go:171","msg":"trace[237222080] transaction","detail":"{read_only:false; response_revision:14708; number_of_response:1; }","duration":"357.366566ms","start":"2026-06-10T13:25:40.313314Z","end":"2026-06-10T13:25:40.670681Z","steps":["trace[237222080] 'process raft request' (duration: 357.185302ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.6707Z","caller":"traceutil/trace.go:171","msg":"trace[1551752213] transaction","detail":"{read_only:false; response_revision:14707; number_of_response:1; }","duration":"528.0043ms","start":"2026-06-10T13:25:40.142656Z","end":"2026-06-10T13:25:40.67066Z","steps":["trace[1551752213] 'process raft request' (duration: 288.695816ms)","trace[1551752213] 'compare' (duration: 238.230004ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:40.670817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:40.313288Z","time spent":"357.488279ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:25:40.670836Z","caller":"traceutil/trace.go:171","msg":"trace[457339332] transaction","detail":"{read_only:false; response_revision:14710; number_of_response:1; }","duration":"340.620928ms","start":"2026-06-10T13:25:40.330199Z","end":"2026-06-10T13:25:40.67082Z","steps":["trace[457339332] 'process raft request' (duration: 340.470033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.670895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:40.142626Z","time spent":"528.162653ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:25:40.670879Z","caller":"traceutil/trace.go:171","msg":"trace[1815636912] linearizableReadLoop","detail":"{readStateIndex:15229; appliedIndex:15226; }","duration":"345.69105ms","start":"2026-06-10T13:25:40.325131Z","end":"2026-06-10T13:25:40.670822Z","steps":["trace[1815636912] 'read index received' (duration: 106.310874ms)","trace[1815636912] 'applied index is now lower than readState.Index' (duration: 239.373005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:40.671033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:40.330181Z","time spent":"340.745911ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:25:40.67092Z","caller":"traceutil/trace.go:171","msg":"trace[493721144] transaction","detail":"{read_only:false; response_revision:14709; number_of_response:1; }","duration":"356.852652ms","start":"2026-06-10T13:25:40.313876Z","end":"2026-06-10T13:25:40.670729Z","steps":["trace[493721144] 'process raft request' (duration: 356.743519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.671174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.428648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T13:25:40.671258Z","caller":"traceutil/trace.go:171","msg":"trace[1655026637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14710; }","duration":"510.552761ms","start":"2026-06-10T13:25:40.160683Z","end":"2026-06-10T13:25:40.671236Z","steps":["trace[1655026637] 'agreement among raft nodes before linearized reading' (duration: 510.236222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.671358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:40.160666Z","time spent":"510.678095ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T13:25:40.671471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:40.313851Z","time spent":"357.325515ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:26:02.710967Z","caller":"traceutil/trace.go:171","msg":"trace[1779996435] linearizableReadLoop","detail":"{readStateIndex:15548; appliedIndex:15547; }","duration":"160.386046ms","start":"2026-06-10T13:26:02.550555Z","end":"2026-06-10T13:26:02.710941Z","steps":["trace[1779996435] 'read index received' (duration: 160.240862ms)","trace[1779996435] 'applied index is now lower than readState.Index' (duration: 144.024µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:26:02.711115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.53384ms","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-06-10T13:26:02.711153Z","caller":"traceutil/trace.go:171","msg":"trace[152661752] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:15014; }","duration":"160.634804ms","start":"2026-06-10T13:26:02.550504Z","end":"2026-06-10T13:26:02.711139Z","steps":["trace[152661752] 'agreement among raft nodes before linearized reading' (duration: 160.547521ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:02.711121Z","caller":"traceutil/trace.go:171","msg":"trace[1687648784] transaction","detail":"{read_only:false; response_revision:15014; number_of_response:1; }","duration":"499.733718ms","start":"2026-06-10T13:26:02.21134Z","end":"2026-06-10T13:26:02.711074Z","steps":["trace[1687648784] 'process raft request' (duration: 499.426579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:02.711821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:26:02.211322Z","time spent":"500.310224ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:26:02.923722Z","caller":"traceutil/trace.go:171","msg":"trace[2075806889] transaction","detail":"{read_only:false; response_revision:15015; number_of_response:1; }","duration":"183.271326ms","start":"2026-06-10T13:26:02.740403Z","end":"2026-06-10T13:26:02.923675Z","steps":["trace[2075806889] 'process raft request' (duration: 183.091032ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:02.924802Z","caller":"traceutil/trace.go:171","msg":"trace[1053322022] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"168.278057ms","start":"2026-06-10T13:26:02.756507Z","end":"2026-06-10T13:26:02.924785Z","steps":["trace[1053322022] 'process raft request' (duration: 167.952017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:02.925092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.166326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-055d865a-5a24-4f42-8093-94aab7fbb9b1\" ","response":"range_response_count:1 size:3754"} {"level":"info","ts":"2026-06-10T13:26:02.924699Z","caller":"traceutil/trace.go:171","msg":"trace[201084059] linearizableReadLoop","detail":"{readStateIndex:15550; appliedIndex:15548; }","duration":"166.819856ms","start":"2026-06-10T13:26:02.757858Z","end":"2026-06-10T13:26:02.924678Z","steps":["trace[201084059] 'read index received' (duration: 165.54128ms)","trace[201084059] 'applied index is now lower than readState.Index' (duration: 1.277546ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:26:02.925179Z","caller":"traceutil/trace.go:171","msg":"trace[97169468] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-055d865a-5a24-4f42-8093-94aab7fbb9b1; range_end:; response_count:1; response_revision:15017; }","duration":"167.355091ms","start":"2026-06-10T13:26:02.757804Z","end":"2026-06-10T13:26:02.925159Z","steps":["trace[97169468] 'agreement among raft nodes before linearized reading' (duration: 167.073393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:02.925367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.623384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-10T13:26:02.925397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.927396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-10T13:26:02.925416Z","caller":"traceutil/trace.go:171","msg":"trace[108233178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15017; }","duration":"159.697328ms","start":"2026-06-10T13:26:02.765706Z","end":"2026-06-10T13:26:02.925403Z","steps":["trace[108233178] 'agreement among raft nodes before linearized reading' (duration: 159.535034ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:02.925452Z","caller":"traceutil/trace.go:171","msg":"trace[191999540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15017; }","duration":"148.992728ms","start":"2026-06-10T13:26:02.776444Z","end":"2026-06-10T13:26:02.925437Z","steps":["trace[191999540] 'agreement among raft nodes before linearized reading' (duration: 148.804773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:02.925526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.577995ms","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-06-10T13:26:02.925823Z","caller":"traceutil/trace.go:171","msg":"trace[341220350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15017; }","duration":"158.908225ms","start":"2026-06-10T13:26:02.766896Z","end":"2026-06-10T13:26:02.925804Z","steps":["trace[341220350] 'agreement among raft nodes before linearized reading' (duration: 158.506464ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:53.438987Z","caller":"traceutil/trace.go:171","msg":"trace[1492044219] transaction","detail":"{read_only:false; response_revision:15648; number_of_response:1; }","duration":"190.040326ms","start":"2026-06-10T13:26:53.248924Z","end":"2026-06-10T13:26:53.438964Z","steps":["trace[1492044219] 'process raft request' (duration: 189.84861ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:53.566841Z","caller":"traceutil/trace.go:171","msg":"trace[816716103] linearizableReadLoop","detail":"{readStateIndex:16198; appliedIndex:16196; }","duration":"226.140976ms","start":"2026-06-10T13:26:53.340676Z","end":"2026-06-10T13:26:53.566817Z","steps":["trace[816716103] 'read index received' (duration: 98.164515ms)","trace[816716103] 'applied index is now lower than readState.Index' (duration: 127.975461ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:26:53.566892Z","caller":"traceutil/trace.go:171","msg":"trace[2040937288] transaction","detail":"{read_only:false; response_revision:15649; number_of_response:1; }","duration":"317.749017ms","start":"2026-06-10T13:26:53.249101Z","end":"2026-06-10T13:26:53.56685Z","steps":["trace[2040937288] 'process raft request' (duration: 304.899568ms)","trace[2040937288] 'compare' (duration: 12.699125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:26:53.567018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.33055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:26:53.567059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.203578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-06-10T13:26:53.567062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:26:53.249068Z","time spent":"317.891921ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:26:53.56709Z","caller":"traceutil/trace.go:171","msg":"trace[1337842660] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15649; }","duration":"226.284388ms","start":"2026-06-10T13:26:53.340797Z","end":"2026-06-10T13:26:53.567081Z","steps":["trace[1337842660] 'agreement among raft nodes before linearized reading' (duration: 226.158725ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:53.567095Z","caller":"traceutil/trace.go:171","msg":"trace[578755915] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15649; }","duration":"226.464724ms","start":"2026-06-10T13:26:53.340609Z","end":"2026-06-10T13:26:53.567074Z","steps":["trace[578755915] 'agreement among raft nodes before linearized reading' (duration: 226.341341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:53.567203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.669901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:26:53.567338Z","caller":"traceutil/trace.go:171","msg":"trace[1111455665] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15649; }","duration":"225.819176ms","start":"2026-06-10T13:26:53.341489Z","end":"2026-06-10T13:26:53.567308Z","steps":["trace[1111455665] 'agreement among raft nodes before linearized reading' (duration: 225.652102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:53.567385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.461625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"warn","ts":"2026-06-10T13:26:53.56746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.799338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:26:53.567475Z","caller":"traceutil/trace.go:171","msg":"trace[1834427158] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15649; }","duration":"226.565187ms","start":"2026-06-10T13:26:53.34089Z","end":"2026-06-10T13:26:53.567455Z","steps":["trace[1834427158] 'agreement among raft nodes before linearized reading' (duration: 226.292379ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:26:53.567526Z","caller":"traceutil/trace.go:171","msg":"trace[1295663027] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15649; }","duration":"224.90355ms","start":"2026-06-10T13:26:53.342606Z","end":"2026-06-10T13:26:53.567509Z","steps":["trace[1295663027] 'agreement among raft nodes before linearized reading' (duration: 224.742206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:26:53.567438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.982171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kv82n\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-06-10T13:26:53.567654Z","caller":"traceutil/trace.go:171","msg":"trace[1392776798] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kv82n; range_end:; response_count:1; response_revision:15649; }","duration":"108.272618ms","start":"2026-06-10T13:26:53.459359Z","end":"2026-06-10T13:26:53.567632Z","steps":["trace[1392776798] 'agreement among raft nodes before linearized reading' (duration: 107.677072ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:15.842581Z","caller":"traceutil/trace.go:171","msg":"trace[24561744] transaction","detail":"{read_only:false; response_revision:15766; number_of_response:1; }","duration":"109.982506ms","start":"2026-06-10T13:27:15.732569Z","end":"2026-06-10T13:27:15.842552Z","steps":["trace[24561744] 'process raft request' (duration: 109.588505ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:27.400428Z","caller":"traceutil/trace.go:171","msg":"trace[374585816] transaction","detail":"{read_only:false; response_revision:15819; number_of_response:1; }","duration":"148.221976ms","start":"2026-06-10T13:27:27.252178Z","end":"2026-06-10T13:27:27.4004Z","steps":["trace[374585816] 'process raft request' (duration: 148.01978ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:27.98615Z","caller":"traceutil/trace.go:171","msg":"trace[247026318] transaction","detail":"{read_only:false; response_revision:15820; number_of_response:1; }","duration":"723.932249ms","start":"2026-06-10T13:27:27.2622Z","end":"2026-06-10T13:27:27.986132Z","steps":["trace[247026318] 'process raft request' (duration: 723.782916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.986264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.26218Z","time spent":"724.029602ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:27:27.986499Z","caller":"traceutil/trace.go:171","msg":"trace[1464967198] linearizableReadLoop","detail":"{readStateIndex:16378; appliedIndex:16374; }","duration":"612.06095ms","start":"2026-06-10T13:27:27.374413Z","end":"2026-06-10T13:27:27.986474Z","steps":["trace[1464967198] 'read index received' (duration: 25.696128ms)","trace[1464967198] 'applied index is now lower than readState.Index' (duration: 586.363482ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:27:27.986532Z","caller":"traceutil/trace.go:171","msg":"trace[1276255540] transaction","detail":"{read_only:false; response_revision:15821; number_of_response:1; }","duration":"721.544533ms","start":"2026-06-10T13:27:27.264924Z","end":"2026-06-10T13:27:27.986468Z","steps":["trace[1276255540] 'process raft request' (duration: 721.179703ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:27.986536Z","caller":"traceutil/trace.go:171","msg":"trace[1541469709] transaction","detail":"{read_only:false; response_revision:15822; number_of_response:1; }","duration":"658.098568ms","start":"2026-06-10T13:27:27.328412Z","end":"2026-06-10T13:27:27.986511Z","steps":["trace[1541469709] 'process raft request' (duration: 657.930203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.640381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-10T13:27:27.987094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.2649Z","time spent":"722.089037ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:27:27.987164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.811846ms","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-06-10T13:27:27.987184Z","caller":"traceutil/trace.go:171","msg":"trace[742301213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15822; }","duration":"348.717804ms","start":"2026-06-10T13:27:27.638458Z","end":"2026-06-10T13:27:27.987175Z","steps":["trace[742301213] 'agreement among raft nodes before linearized reading' (duration: 348.351773ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:27.987211Z","caller":"traceutil/trace.go:171","msg":"trace[318664496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15822; }","duration":"609.874549ms","start":"2026-06-10T13:27:27.377321Z","end":"2026-06-10T13:27:27.987195Z","steps":["trace[318664496] 'agreement among raft nodes before linearized reading' (duration: 609.348194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.638435Z","time spent":"348.768105ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T13:27:27.986753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.021596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:27:27.987013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.255741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:27:27.987311Z","caller":"traceutil/trace.go:171","msg":"trace[1510601221] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15822; }","duration":"229.612112ms","start":"2026-06-10T13:27:27.757678Z","end":"2026-06-10T13:27:27.98729Z","steps":["trace[1510601221] 'agreement among raft nodes before linearized reading' (duration: 229.042905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.377314Z","time spent":"609.93352ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T13:27:27.986789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.428809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:27.987422Z","caller":"traceutil/trace.go:171","msg":"trace[549857621] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15822; }","duration":"229.077357ms","start":"2026-06-10T13:27:27.758335Z","end":"2026-06-10T13:27:27.987412Z","steps":["trace[549857621] 'agreement among raft nodes before linearized reading' (duration: 228.440239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.064988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-10T13:27:27.987498Z","caller":"traceutil/trace.go:171","msg":"trace[1241888917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15822; }","duration":"608.10965ms","start":"2026-06-10T13:27:27.379376Z","end":"2026-06-10T13:27:27.987486Z","steps":["trace[1241888917] 'agreement among raft nodes before linearized reading' (duration: 607.513563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.379364Z","time spent":"608.154031ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-10T13:27:27.987322Z","caller":"traceutil/trace.go:171","msg":"trace[1491295855] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15822; }","duration":"225.59521ms","start":"2026-06-10T13:27:27.761713Z","end":"2026-06-10T13:27:27.987308Z","steps":["trace[1491295855] 'agreement among raft nodes before linearized reading' (duration: 225.159598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.98683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.387029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-10T13:27:27.986923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.853932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-10T13:27:27.987689Z","caller":"traceutil/trace.go:171","msg":"trace[1366605985] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15822; }","duration":"228.623724ms","start":"2026-06-10T13:27:27.759053Z","end":"2026-06-10T13:27:27.987677Z","steps":["trace[1366605985] 'agreement among raft nodes before linearized reading' (duration: 227.77771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.986925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.762164ms","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-06-10T13:27:27.987762Z","caller":"traceutil/trace.go:171","msg":"trace[454023360] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:15822; }","duration":"236.640509ms","start":"2026-06-10T13:27:27.751114Z","end":"2026-06-10T13:27:27.987755Z","steps":["trace[454023360] 'agreement among raft nodes before linearized reading' (duration: 235.782505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.986865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.050417ms","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-06-10T13:27:27.98789Z","caller":"traceutil/trace.go:171","msg":"trace[1058822949] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:15822; }","duration":"343.122637ms","start":"2026-06-10T13:27:27.644759Z","end":"2026-06-10T13:27:27.987881Z","steps":["trace[1058822949] 'agreement among raft nodes before linearized reading' (duration: 341.951394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.987916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.644751Z","time spent":"343.155708ms","remote":"127.0.0.1:34752","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":"warn","ts":"2026-06-10T13:27:27.98784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.305478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:27:27.988033Z","caller":"traceutil/trace.go:171","msg":"trace[406537151] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15822; }","duration":"227.533044ms","start":"2026-06-10T13:27:27.760481Z","end":"2026-06-10T13:27:27.988014Z","steps":["trace[406537151] 'agreement among raft nodes before linearized reading' (duration: 226.33634ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:27.987657Z","caller":"traceutil/trace.go:171","msg":"trace[1341143704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15822; }","duration":"613.289825ms","start":"2026-06-10T13:27:27.37436Z","end":"2026-06-10T13:27:27.98765Z","steps":["trace[1341143704] 'agreement among raft nodes before linearized reading' (duration: 612.208985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.988163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.374336Z","time spent":"613.816789ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T13:27:27.986913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.844973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-06-10T13:27:27.988249Z","caller":"traceutil/trace.go:171","msg":"trace[634991811] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15822; }","duration":"229.224532ms","start":"2026-06-10T13:27:27.759012Z","end":"2026-06-10T13:27:27.988236Z","steps":["trace[634991811] 'agreement among raft nodes before linearized reading' (duration: 227.689058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.98865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.328376Z","time spent":"658.732126ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:27:27.987032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.306582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-06-10T13:27:27.990244Z","caller":"traceutil/trace.go:171","msg":"trace[1141936457] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15822; }","duration":"588.556423ms","start":"2026-06-10T13:27:27.401658Z","end":"2026-06-10T13:27:27.990214Z","steps":["trace[1141936457] 'agreement among raft nodes before linearized reading' (duration: 585.116026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:27.991584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:27.401642Z","time spent":"589.824658ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-06-10T13:27:28.256241Z","caller":"traceutil/trace.go:171","msg":"trace[40063617] linearizableReadLoop","detail":"{readStateIndex:16383; appliedIndex:16378; }","duration":"258.174581ms","start":"2026-06-10T13:27:27.998043Z","end":"2026-06-10T13:27:28.256217Z","steps":["trace[40063617] 'read index received' (duration: 211.815174ms)","trace[40063617] 'applied index is now lower than readState.Index' (duration: 46.356137ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:27:28.256379Z","caller":"traceutil/trace.go:171","msg":"trace[1614962869] transaction","detail":"{read_only:false; response_revision:15825; number_of_response:1; }","duration":"260.212808ms","start":"2026-06-10T13:27:27.996153Z","end":"2026-06-10T13:27:28.256366Z","steps":["trace[1614962869] 'process raft request' (duration: 259.960271ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.256525Z","caller":"traceutil/trace.go:171","msg":"trace[2069460433] transaction","detail":"{read_only:false; response_revision:15826; number_of_response:1; }","duration":"260.329262ms","start":"2026-06-10T13:27:27.996187Z","end":"2026-06-10T13:27:28.256516Z","steps":["trace[2069460433] 'process raft request' (duration: 259.962161ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.256724Z","caller":"traceutil/trace.go:171","msg":"trace[586150543] transaction","detail":"{read_only:false; response_revision:15823; number_of_response:1; }","duration":"262.276936ms","start":"2026-06-10T13:27:27.994436Z","end":"2026-06-10T13:27:28.256712Z","steps":["trace[586150543] 'process raft request' (duration: 215.310262ms)","trace[586150543] 'compare' (duration: 46.171792ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:27:28.256754Z","caller":"traceutil/trace.go:171","msg":"trace[1769837231] transaction","detail":"{read_only:false; response_revision:15827; number_of_response:1; }","duration":"259.244342ms","start":"2026-06-10T13:27:27.997496Z","end":"2026-06-10T13:27:28.25674Z","steps":["trace[1769837231] 'process raft request' (duration: 258.686337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.256857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.830895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:27:28.256857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.693029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:28.25689Z","caller":"traceutil/trace.go:171","msg":"trace[2065461965] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15827; }","duration":"147.936739ms","start":"2026-06-10T13:27:28.108944Z","end":"2026-06-10T13:27:28.256881Z","steps":["trace[2065461965] 'agreement among raft nodes before linearized reading' (duration: 147.874267ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.256893Z","caller":"traceutil/trace.go:171","msg":"trace[1714538594] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15827; }","duration":"149.340598ms","start":"2026-06-10T13:27:28.107543Z","end":"2026-06-10T13:27:28.256884Z","steps":["trace[1714538594] 'agreement among raft nodes before linearized reading' (duration: 149.272196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.256887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.059561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-10T13:27:28.256969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.664657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:28.256999Z","caller":"traceutil/trace.go:171","msg":"trace[752514143] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15827; }","duration":"242.72665ms","start":"2026-06-10T13:27:28.014264Z","end":"2026-06-10T13:27:28.256991Z","steps":["trace[752514143] 'agreement among raft nodes before linearized reading' (duration: 242.685009ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.256989Z","caller":"traceutil/trace.go:171","msg":"trace[225829059] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15827; }","duration":"232.198385ms","start":"2026-06-10T13:27:28.024768Z","end":"2026-06-10T13:27:28.256967Z","steps":["trace[225829059] 'agreement among raft nodes before linearized reading' (duration: 231.949879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.257112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.566324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-10T13:27:28.257141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.649141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:28.257167Z","caller":"traceutil/trace.go:171","msg":"trace[472987405] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15827; }","duration":"151.713604ms","start":"2026-06-10T13:27:28.105445Z","end":"2026-06-10T13:27:28.257158Z","steps":["trace[472987405] 'agreement among raft nodes before linearized reading' (duration: 151.678752ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.257166Z","caller":"traceutil/trace.go:171","msg":"trace[650672975] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15827; }","duration":"149.640676ms","start":"2026-06-10T13:27:28.107511Z","end":"2026-06-10T13:27:28.257152Z","steps":["trace[650672975] 'agreement among raft nodes before linearized reading' (duration: 149.512681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.257147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.11163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-06-10T13:27:28.257293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.299275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-10T13:27:28.257311Z","caller":"traceutil/trace.go:171","msg":"trace[2103325061] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15827; }","duration":"243.306175ms","start":"2026-06-10T13:27:28.01398Z","end":"2026-06-10T13:27:28.257286Z","steps":["trace[2103325061] 'agreement among raft nodes before linearized reading' (duration: 242.854463ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.257354Z","caller":"traceutil/trace.go:171","msg":"trace[191964422] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15827; }","duration":"243.387427ms","start":"2026-06-10T13:27:28.013953Z","end":"2026-06-10T13:27:28.25734Z","steps":["trace[191964422] 'agreement among raft nodes before linearized reading' (duration: 243.238724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.257421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.372205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737449"} {"level":"info","ts":"2026-06-10T13:27:28.257459Z","caller":"traceutil/trace.go:171","msg":"trace[338061190] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15827; }","duration":"259.430726ms","start":"2026-06-10T13:27:27.99802Z","end":"2026-06-10T13:27:28.257451Z","steps":["trace[338061190] 'agreement among raft nodes before linearized reading' (duration: 258.659235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.257956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.943778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737449"} {"level":"info","ts":"2026-06-10T13:27:28.257992Z","caller":"traceutil/trace.go:171","msg":"trace[510041944] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15827; }","duration":"256.982228ms","start":"2026-06-10T13:27:28.001003Z","end":"2026-06-10T13:27:28.257985Z","steps":["trace[510041944] 'agreement among raft nodes before linearized reading' (duration: 256.228287ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.256314Z","caller":"traceutil/trace.go:171","msg":"trace[979852227] transaction","detail":"{read_only:false; response_revision:15824; number_of_response:1; }","duration":"260.407884ms","start":"2026-06-10T13:27:27.995847Z","end":"2026-06-10T13:27:28.256255Z","steps":["trace[979852227] 'process raft request' (duration: 260.218189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.261438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.457552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737449"} {"level":"info","ts":"2026-06-10T13:27:28.261533Z","caller":"traceutil/trace.go:171","msg":"trace[778415364] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15827; }","duration":"261.860295ms","start":"2026-06-10T13:27:27.999645Z","end":"2026-06-10T13:27:28.261505Z","steps":["trace[778415364] 'agreement among raft nodes before linearized reading' (duration: 257.062871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.698821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.22292ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T13:27:28.698951Z","caller":"traceutil/trace.go:171","msg":"trace[989019914] linearizableReadLoop","detail":"{readStateIndex:16384; appliedIndex:16383; }","duration":"307.940434ms","start":"2026-06-10T13:27:28.390992Z","end":"2026-06-10T13:27:28.698933Z","steps":["trace[989019914] 'read index received' (duration: 176.530487ms)","trace[989019914] 'applied index is now lower than readState.Index' (duration: 131.408447ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:27:28.699088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.090598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:28.699127Z","caller":"traceutil/trace.go:171","msg":"trace[1759344641] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15827; }","duration":"308.20159ms","start":"2026-06-10T13:27:28.390913Z","end":"2026-06-10T13:27:28.699114Z","steps":["trace[1759344641] 'agreement among raft nodes before linearized reading' (duration: 308.123919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.699167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:28.390873Z","time spent":"308.285424ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-06-10T13:27:28.699191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.931473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:27:28.699245Z","caller":"traceutil/trace.go:171","msg":"trace[1189306952] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15827; }","duration":"308.015385ms","start":"2026-06-10T13:27:28.391217Z","end":"2026-06-10T13:27:28.699232Z","steps":["trace[1189306952] 'agreement among raft nodes before linearized reading' (duration: 307.842501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.699285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:28.391202Z","time spent":"308.077957ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-06-10T13:27:28.699559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.298463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:28.699584Z","caller":"traceutil/trace.go:171","msg":"trace[432722318] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15827; }","duration":"308.361366ms","start":"2026-06-10T13:27:28.391216Z","end":"2026-06-10T13:27:28.699578Z","steps":["trace[432722318] 'agreement among raft nodes before linearized reading' (duration: 308.320054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.699608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:28.391186Z","time spent":"308.416997ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-06-10T13:27:28.699611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.259583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:27:28.699869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.513341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sl8v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-10T13:27:28.699915Z","caller":"traceutil/trace.go:171","msg":"trace[1501656549] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sl8v5; range_end:; response_count:1; response_revision:15827; }","duration":"223.579884ms","start":"2026-06-10T13:27:28.476324Z","end":"2026-06-10T13:27:28.699903Z","steps":["trace[1501656549] 'agreement among raft nodes before linearized reading' (duration: 223.436659ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:28.699904Z","caller":"traceutil/trace.go:171","msg":"trace[670471964] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15827; }","duration":"308.562011ms","start":"2026-06-10T13:27:28.391246Z","end":"2026-06-10T13:27:28.699808Z","steps":["trace[670471964] 'agreement among raft nodes before linearized reading' (duration: 308.254902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:28.700103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:28.391219Z","time spent":"308.846099ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-06-10T13:30:18.877857Z","caller":"traceutil/trace.go:171","msg":"trace[645597170] transaction","detail":"{read_only:false; response_revision:17222; number_of_response:1; }","duration":"123.873805ms","start":"2026-06-10T13:30:18.753952Z","end":"2026-06-10T13:30:18.877826Z","steps":["trace[645597170] 'process raft request' (duration: 123.687171ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:30:29.581862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14635} {"level":"info","ts":"2026-06-10T13:30:29.656801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14635,"took":"72.667732ms","hash":46791135,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T13:30:29.656932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":46791135,"revision":14635,"compact-revision":12615} {"level":"info","ts":"2026-06-10T13:34:40.442992Z","caller":"traceutil/trace.go:171","msg":"trace[627466588] linearizableReadLoop","detail":"{readStateIndex:19494; appliedIndex:19493; }","duration":"101.64006ms","start":"2026-06-10T13:34:40.341306Z","end":"2026-06-10T13:34:40.442946Z","steps":["trace[627466588] 'read index received' (duration: 101.2962ms)","trace[627466588] 'applied index is now lower than readState.Index' (duration: 342.24µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:34:40.443042Z","caller":"traceutil/trace.go:171","msg":"trace[2044113721] transaction","detail":"{read_only:false; response_revision:18836; number_of_response:1; }","duration":"154.998932ms","start":"2026-06-10T13:34:40.288003Z","end":"2026-06-10T13:34:40.443002Z","steps":["trace[2044113721] 'process raft request' (duration: 154.627631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:34:40.443339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.003392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T13:34:40.443408Z","caller":"traceutil/trace.go:171","msg":"trace[728372014] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18836; }","duration":"102.093864ms","start":"2026-06-10T13:34:40.341299Z","end":"2026-06-10T13:34:40.443393Z","steps":["trace[728372014] 'agreement among raft nodes before linearized reading' (duration: 101.821147ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:34:41.155329Z","caller":"traceutil/trace.go:171","msg":"trace[1122018039] linearizableReadLoop","detail":"{readStateIndex:19504; appliedIndex:19503; }","duration":"104.801479ms","start":"2026-06-10T13:34:41.0505Z","end":"2026-06-10T13:34:41.155302Z","steps":["trace[1122018039] 'read index received' (duration: 22.148578ms)","trace[1122018039] 'applied index is now lower than readState.Index' (duration: 82.651921ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:34:41.155487Z","caller":"traceutil/trace.go:171","msg":"trace[1513615279] transaction","detail":"{read_only:false; response_revision:18846; number_of_response:1; }","duration":"128.4517ms","start":"2026-06-10T13:34:41.027023Z","end":"2026-06-10T13:34:41.155474Z","steps":["trace[1513615279] 'process raft request' (duration: 45.733618ms)","trace[1513615279] 'compare' (duration: 82.403984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:34:41.155743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.220582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-10T13:34:41.15578Z","caller":"traceutil/trace.go:171","msg":"trace[443934537] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18846; }","duration":"105.320254ms","start":"2026-06-10T13:34:41.05045Z","end":"2026-06-10T13:34:41.155771Z","steps":["trace[443934537] 'agreement among raft nodes before linearized reading' (duration: 105.16754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:34:41.155908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.491781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T13:34:41.155961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.44694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T13:34:41.155998Z","caller":"traceutil/trace.go:171","msg":"trace[389305270] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:18846; }","duration":"104.605044ms","start":"2026-06-10T13:34:41.051372Z","end":"2026-06-10T13:34:41.155977Z","steps":["trace[389305270] 'agreement among raft nodes before linearized reading' (duration: 104.412228ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:34:41.156056Z","caller":"traceutil/trace.go:171","msg":"trace[15406318] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:18846; }","duration":"104.598215ms","start":"2026-06-10T13:34:41.051427Z","end":"2026-06-10T13:34:41.156026Z","steps":["trace[15406318] 'agreement among raft nodes before linearized reading' (duration: 104.430279ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:35:29.587308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17320} {"level":"info","ts":"2026-06-10T13:35:29.69648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17320,"took":"102.712361ms","hash":1285252891,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":29933568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-10T13:35:29.696543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1285252891,"revision":17320,"compact-revision":14635} {"level":"info","ts":"2026-06-10T13:36:13.153823Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T13:36:13.165903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-10T13:36:13.16602Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-10T13:40:29.594594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19091} {"level":"info","ts":"2026-06-10T13:40:29.683518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19091,"took":"85.040677ms","hash":2881055974,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":24055808,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-10T13:40:29.683581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2881055974,"revision":19091,"compact-revision":17320}