{"level":"warn","ts":"2026-07-02T16:29:28.503713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T16:29:28.503898Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-02T16:29:28.503976Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T16:29:28.503987Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-02T16:29:28.504006Z","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-07-02T16:29:28.504702Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-02T16:29:28.504781Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-02T16:29:28.508963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.949974ms"} {"level":"info","ts":"2026-07-02T16:29:28.514754Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-02T16:29:28.514911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T16:29:28.514942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-02T16:29:28.514954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T16:29:28.514969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-02T16:29:28.515029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-02T16:29:28.519861Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T16:29:28.522736Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T16:29:28.524086Z","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-07-02T16:29:28.52632Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T16:29:28.526663Z","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-07-02T16:29:28.526809Z","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-07-02T16:29:28.526825Z","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-07-02T16:29:28.526816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T16:29:28.529971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-02T16:29:28.530435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-02T16:29:28.532352Z","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-07-02T16:29:28.532401Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-02T16:29:28.532443Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-02T16:29:28.532739Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T16:29:28.532803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T16:29:28.716253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T16:29:28.71641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T16:29:28.716473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-02T16:29:28.716518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-02T16:29:28.716631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-02T16:29:28.716712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-02T16:29:28.716772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-02T16:29:28.718003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T16:29:28.718072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T16:29:28.718106Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:28.718155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T16:29:28.718491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T16:29:28.718514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T16:29:28.719008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:28.719074Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:28.719092Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:28.720952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T16:29:28.721923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-02T16:29:45.10252Z","caller":"traceutil/trace.go:171","msg":"trace[1261380005] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"155.736091ms","start":"2026-07-02T16:29:44.94675Z","end":"2026-07-02T16:29:45.102486Z","steps":["trace[1261380005] 'process raft request' (duration: 155.155845ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:55.853433Z","caller":"traceutil/trace.go:171","msg":"trace[311505519] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"105.780686ms","start":"2026-07-02T16:29:55.74763Z","end":"2026-07-02T16:29:55.853411Z","steps":["trace[311505519] 'process raft request' (duration: 105.663393ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:30:14.279981Z","caller":"traceutil/trace.go:171","msg":"trace[672597727] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"333.823908ms","start":"2026-07-02T16:30:13.946131Z","end":"2026-07-02T16:30:14.279955Z","steps":["trace[672597727] 'read index received' (duration: 331.551272ms)","trace[672597727] 'applied index is now lower than readState.Index' (duration: 2.271566ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:30:14.280061Z","caller":"traceutil/trace.go:171","msg":"trace[316286579] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"400.237801ms","start":"2026-07-02T16:30:13.879761Z","end":"2026-07-02T16:30:14.279999Z","steps":["trace[316286579] 'process raft request' (duration: 397.949274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:30:14.280258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.44887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4hz75\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-02T16:30:14.280204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.076206ms","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-07-02T16:30:14.280295Z","caller":"traceutil/trace.go:171","msg":"trace[1286715197] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4hz75; range_end:; response_count:1; response_revision:587; }","duration":"265.552163ms","start":"2026-07-02T16:30:14.014732Z","end":"2026-07-02T16:30:14.280285Z","steps":["trace[1286715197] 'agreement among raft nodes before linearized reading' (duration: 265.360357ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:30:14.280314Z","caller":"traceutil/trace.go:171","msg":"trace[188380278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"334.246721ms","start":"2026-07-02T16:30:13.946048Z","end":"2026-07-02T16:30:14.280295Z","steps":["trace[188380278] 'agreement among raft nodes before linearized reading' (duration: 333.995494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:30:14.280349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:30:13.946008Z","time spent":"334.331903ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T16:30:14.280936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:30:13.879728Z","time spent":"400.423046ms","remote":"127.0.0.1:45182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T16:30:29.614615Z","caller":"traceutil/trace.go:171","msg":"trace[21786322] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"123.118049ms","start":"2026-07-02T16:30:29.491466Z","end":"2026-07-02T16:30:29.614584Z","steps":["trace[21786322] 'process raft request' (duration: 122.845781ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:31:43.123399Z","caller":"traceutil/trace.go:171","msg":"trace[276256131] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"120.978822ms","start":"2026-07-02T16:31:43.002388Z","end":"2026-07-02T16:31:43.123366Z","steps":["trace[276256131] 'process raft request' (duration: 120.796007ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:32:38.095511Z","caller":"traceutil/trace.go:171","msg":"trace[1919857836] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"148.9585ms","start":"2026-07-02T16:32:37.946521Z","end":"2026-07-02T16:32:38.09548Z","steps":["trace[1919857836] 'process raft request' (duration: 148.750354ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:32:38.106371Z","caller":"traceutil/trace.go:171","msg":"trace[758730761] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"141.703302ms","start":"2026-07-02T16:32:37.964632Z","end":"2026-07-02T16:32:38.106336Z","steps":["trace[758730761] 'process raft request' (duration: 141.515117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:32:38.535681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.520462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T16:32:38.536214Z","caller":"traceutil/trace.go:171","msg":"trace[1677800600] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"266.267928ms","start":"2026-07-02T16:32:38.269696Z","end":"2026-07-02T16:32:38.535963Z","steps":["trace[1677800600] 'process raft request' (duration: 112.842981ms)","trace[1677800600] 'compare' (duration: 152.391177ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:32:38.536325Z","caller":"traceutil/trace.go:171","msg":"trace[441187448] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"265.956088ms","start":"2026-07-02T16:32:38.270331Z","end":"2026-07-02T16:32:38.536287Z","steps":["trace[441187448] 'process raft request' (duration: 265.555116ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:32:38.868666Z","caller":"traceutil/trace.go:171","msg":"trace[1035783292] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"195.619011ms","start":"2026-07-02T16:32:38.673024Z","end":"2026-07-02T16:32:38.868643Z","steps":["trace[1035783292] 'read index received' (duration: 195.447447ms)","trace[1035783292] 'applied index is now lower than readState.Index' (duration: 170.294µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:32:38.86873Z","caller":"traceutil/trace.go:171","msg":"trace[234019462] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"228.55716ms","start":"2026-07-02T16:32:38.640141Z","end":"2026-07-02T16:32:38.868698Z","steps":["trace[234019462] 'process raft request' (duration: 228.353974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:32:38.868843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.808217ms","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-07-02T16:32:38.868887Z","caller":"traceutil/trace.go:171","msg":"trace[1916885415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"195.90131ms","start":"2026-07-02T16:32:38.672972Z","end":"2026-07-02T16:32:38.868874Z","steps":["trace[1916885415] 'agreement among raft nodes before linearized reading' (duration: 195.757976ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:33:22.127709Z","caller":"traceutil/trace.go:171","msg":"trace[1449218071] linearizableReadLoop","detail":"{readStateIndex:2692; appliedIndex:2690; }","duration":"130.655812ms","start":"2026-07-02T16:33:21.997032Z","end":"2026-07-02T16:33:22.127688Z","steps":["trace[1449218071] 'read index received' (duration: 127.860281ms)","trace[1449218071] 'applied index is now lower than readState.Index' (duration: 2.794231ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:33:22.127853Z","caller":"traceutil/trace.go:171","msg":"trace[1234712632] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"158.110614ms","start":"2026-07-02T16:33:21.969665Z","end":"2026-07-02T16:33:22.127775Z","steps":["trace[1234712632] 'process raft request' (duration: 157.881328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:33:22.128052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.003681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-02T16:33:22.128126Z","caller":"traceutil/trace.go:171","msg":"trace[907593558] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2594; }","duration":"131.138416ms","start":"2026-07-02T16:33:21.996965Z","end":"2026-07-02T16:33:22.128103Z","steps":["trace[907593558] 'agreement among raft nodes before linearized reading' (duration: 130.891359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:36:21.063126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.21388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T16:36:21.063243Z","caller":"traceutil/trace.go:171","msg":"trace[2026177587] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3764; }","duration":"100.401166ms","start":"2026-07-02T16:36:20.962818Z","end":"2026-07-02T16:36:21.06322Z","steps":["trace[2026177587] 'count revisions from in-memory index tree' (duration: 100.106218ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:36:26.429313Z","caller":"traceutil/trace.go:171","msg":"trace[1112204633] transaction","detail":"{read_only:false; response_revision:3794; number_of_response:1; }","duration":"142.288984ms","start":"2026-07-02T16:36:26.286961Z","end":"2026-07-02T16:36:26.42925Z","steps":["trace[1112204633] 'process raft request' (duration: 140.344618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:37:50.546418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.694673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2da72558-aab4-4f67-8fbe-27fbb838b5dd\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-02T16:37:50.546513Z","caller":"traceutil/trace.go:171","msg":"trace[845287151] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2da72558-aab4-4f67-8fbe-27fbb838b5dd; range_end:; response_count:1; response_revision:4977; }","duration":"225.845247ms","start":"2026-07-02T16:37:50.320647Z","end":"2026-07-02T16:37:50.546493Z","steps":["trace[845287151] 'range keys from in-memory index tree' (duration: 225.465835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:37:50.546783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.521105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:37:50.546846Z","caller":"traceutil/trace.go:171","msg":"trace[18998293] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4977; }","duration":"193.584777ms","start":"2026-07-02T16:37:50.353246Z","end":"2026-07-02T16:37:50.546831Z","steps":["trace[18998293] 'range keys from in-memory index tree' (duration: 193.463653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:37:50.547314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.386887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2da72558-aab4-4f67-8fbe-27fbb838b5dd\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-02T16:37:50.547362Z","caller":"traceutil/trace.go:171","msg":"trace[1444913495] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2da72558-aab4-4f67-8fbe-27fbb838b5dd; range_end:; response_count:1; response_revision:4977; }","duration":"116.46537ms","start":"2026-07-02T16:37:50.430885Z","end":"2026-07-02T16:37:50.54735Z","steps":["trace[1444913495] 'range keys from in-memory index tree' (duration: 116.266674ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:37:52.719847Z","caller":"traceutil/trace.go:171","msg":"trace[1387010171] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5010; }","duration":"109.096329ms","start":"2026-07-02T16:37:52.610669Z","end":"2026-07-02T16:37:52.719766Z","steps":["trace[1387010171] 'process raft request' (duration: 108.990486ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.649817Z","caller":"traceutil/trace.go:171","msg":"trace[1928458031] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"114.37697ms","start":"2026-07-02T16:38:03.535409Z","end":"2026-07-02T16:38:03.649786Z","steps":["trace[1928458031] 'process raft request' (duration: 73.406922ms)","trace[1928458031] 'compare' (duration: 40.728541ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:03.65024Z","caller":"traceutil/trace.go:171","msg":"trace[210165086] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"113.224706ms","start":"2026-07-02T16:38:03.536972Z","end":"2026-07-02T16:38:03.650197Z","steps":["trace[210165086] 'process raft request' (duration: 112.739593ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.650847Z","caller":"traceutil/trace.go:171","msg":"trace[885950392] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"108.559293ms","start":"2026-07-02T16:38:03.542249Z","end":"2026-07-02T16:38:03.650808Z","steps":["trace[885950392] 'process raft request' (duration: 108.374328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.682741Z","caller":"traceutil/trace.go:171","msg":"trace[379703288] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"126.520485ms","start":"2026-07-02T16:38:03.556195Z","end":"2026-07-02T16:38:03.682715Z","steps":["trace[379703288] 'process raft request' (duration: 126.404712ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.850769Z","caller":"traceutil/trace.go:171","msg":"trace[698475832] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"162.486091ms","start":"2026-07-02T16:38:03.688254Z","end":"2026-07-02T16:38:03.85074Z","steps":["trace[698475832] 'process raft request' (duration: 162.314556ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.86106Z","caller":"traceutil/trace.go:171","msg":"trace[1129027426] linearizableReadLoop","detail":"{readStateIndex:5438; appliedIndex:5434; }","duration":"152.395593ms","start":"2026-07-02T16:38:03.708644Z","end":"2026-07-02T16:38:03.86104Z","steps":["trace[1129027426] 'read index received' (duration: 141.887684ms)","trace[1129027426] 'applied index is now lower than readState.Index' (duration: 10.506879ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:03.861184Z","caller":"traceutil/trace.go:171","msg":"trace[976048732] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"171.314812ms","start":"2026-07-02T16:38:03.689844Z","end":"2026-07-02T16:38:03.861158Z","steps":["trace[976048732] 'process raft request' (duration: 171.140657ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.861195Z","caller":"traceutil/trace.go:171","msg":"trace[1425226346] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"172.023812ms","start":"2026-07-02T16:38:03.689159Z","end":"2026-07-02T16:38:03.861182Z","steps":["trace[1425226346] 'process raft request' (duration: 171.669292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.861075Z","caller":"traceutil/trace.go:171","msg":"trace[815225758] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"171.800796ms","start":"2026-07-02T16:38:03.689254Z","end":"2026-07-02T16:38:03.861055Z","steps":["trace[815225758] 'process raft request' (duration: 171.695683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:38:03.861255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.592828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-02T16:38:03.861388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.2912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:38:03.861514Z","caller":"traceutil/trace.go:171","msg":"trace[1776034551] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5245; }","duration":"152.518767ms","start":"2026-07-02T16:38:03.70897Z","end":"2026-07-02T16:38:03.861489Z","steps":["trace[1776034551] 'agreement among raft nodes before linearized reading' (duration: 152.263339ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.861442Z","caller":"traceutil/trace.go:171","msg":"trace[45227248] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5245; }","duration":"152.826536ms","start":"2026-07-02T16:38:03.708598Z","end":"2026-07-02T16:38:03.861424Z","steps":["trace[45227248] 'agreement among raft nodes before linearized reading' (duration: 152.546187ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:17.789996Z","caller":"traceutil/trace.go:171","msg":"trace[1618894068] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"125.466306ms","start":"2026-07-02T16:38:17.664493Z","end":"2026-07-02T16:38:17.789959Z","steps":["trace[1618894068] 'process raft request' (duration: 100.530355ms)","trace[1618894068] 'compare' (duration: 24.742324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:38:18.098673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:38:18.098776Z","caller":"traceutil/trace.go:171","msg":"trace[102795722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5496; }","duration":"116.192922ms","start":"2026-07-02T16:38:17.982561Z","end":"2026-07-02T16:38:18.098754Z","steps":["trace[102795722] 'count revisions from in-memory index tree' (duration: 115.912594ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.286924Z","caller":"traceutil/trace.go:171","msg":"trace[1818580062] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"172.26284ms","start":"2026-07-02T16:38:18.114624Z","end":"2026-07-02T16:38:18.286886Z","steps":["trace[1818580062] 'process raft request' (duration: 123.638934ms)","trace[1818580062] 'compare' (duration: 48.358708ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:18.455124Z","caller":"traceutil/trace.go:171","msg":"trace[1994992837] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"162.399298ms","start":"2026-07-02T16:38:18.292673Z","end":"2026-07-02T16:38:18.455072Z","steps":["trace[1994992837] 'process raft request' (duration: 108.196463ms)","trace[1994992837] 'compare' (duration: 54.03728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:38:29.422358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.272149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T16:38:29.422389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.012865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T16:38:29.422447Z","caller":"traceutil/trace.go:171","msg":"trace[819011889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5603; }","duration":"485.407642ms","start":"2026-07-02T16:38:28.937019Z","end":"2026-07-02T16:38:29.422426Z","steps":["trace[819011889] 'range keys from in-memory index tree' (duration: 485.055583ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:29.422487Z","caller":"traceutil/trace.go:171","msg":"trace[1548019978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5603; }","duration":"376.149098ms","start":"2026-07-02T16:38:29.046315Z","end":"2026-07-02T16:38:29.422464Z","steps":["trace[1548019978] 'range keys from in-memory index tree' (duration: 375.8387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:38:29.422555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:38:29.046296Z","time spent":"376.245242ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T16:38:29.42259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.608794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-02T16:38:29.422552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.244087ms","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-07-02T16:38:29.42249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:38:28.936997Z","time spent":"485.484565ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T16:38:29.422669Z","caller":"traceutil/trace.go:171","msg":"trace[371629296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5603; }","duration":"479.378921ms","start":"2026-07-02T16:38:28.943268Z","end":"2026-07-02T16:38:29.422647Z","steps":["trace[371629296] 'range keys from in-memory index tree' (duration: 479.024721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:38:29.422719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:38:28.943206Z","time spent":"479.504864ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T16:38:29.422749Z","caller":"traceutil/trace.go:171","msg":"trace[380406694] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"158.60538ms","start":"2026-07-02T16:38:29.26409Z","end":"2026-07-02T16:38:29.422695Z","steps":["trace[380406694] 'process raft request' (duration: 15.824271ms)","trace[380406694] 'compare' (duration: 142.054959ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:39:28.917442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-07-02T16:39:28.985045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"66.077747ms","hash":1553434127,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":34013184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T16:39:28.985121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1553434127,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-07-02T16:44:28.935581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6099} {"level":"info","ts":"2026-07-02T16:44:29.032769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6099,"took":"94.544383ms","hash":1175481915,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":23826432,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-02T16:44:29.032842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1175481915,"revision":6099,"compact-revision":2980} {"level":"info","ts":"2026-07-02T16:44:32.498209Z","caller":"traceutil/trace.go:171","msg":"trace[1989179436] linearizableReadLoop","detail":"{readStateIndex:8601; appliedIndex:8600; }","duration":"296.708857ms","start":"2026-07-02T16:44:32.20146Z","end":"2026-07-02T16:44:32.498169Z","steps":["trace[1989179436] 'read index received' (duration: 296.51926ms)","trace[1989179436] 'applied index is now lower than readState.Index' (duration: 188.197µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:44:32.498329Z","caller":"traceutil/trace.go:171","msg":"trace[1569807764] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"301.196046ms","start":"2026-07-02T16:44:32.197094Z","end":"2026-07-02T16:44:32.49829Z","steps":["trace[1569807764] 'process raft request' (duration: 300.867646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:44:32.498511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:44:32.197076Z","time spent":"301.33786ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:44:32.498603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.093707ms","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-07-02T16:44:32.49871Z","caller":"traceutil/trace.go:171","msg":"trace[762575549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8309; }","duration":"297.224411ms","start":"2026-07-02T16:44:32.201444Z","end":"2026-07-02T16:44:32.498669Z","steps":["trace[762575549] 'agreement among raft nodes before linearized reading' (duration: 296.923542ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:32.620009Z","caller":"traceutil/trace.go:171","msg":"trace[2096714593] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"117.339068ms","start":"2026-07-02T16:44:32.502653Z","end":"2026-07-02T16:44:32.619992Z","steps":["trace[2096714593] 'process raft request' (duration: 117.284166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:32.620023Z","caller":"traceutil/trace.go:171","msg":"trace[1987641487] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"231.208847ms","start":"2026-07-02T16:44:32.38878Z","end":"2026-07-02T16:44:32.619989Z","steps":["trace[1987641487] 'process raft request' (duration: 218.490691ms)","trace[1987641487] 'compare' (duration: 12.574352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:44:44.910575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.500721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T16:44:44.910774Z","caller":"traceutil/trace.go:171","msg":"trace[1326337799] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"186.691477ms","start":"2026-07-02T16:44:44.724064Z","end":"2026-07-02T16:44:44.910756Z","steps":["trace[1326337799] 'process raft request' (duration: 186.622976ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:44.91081Z","caller":"traceutil/trace.go:171","msg":"trace[1382640708] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"189.459818ms","start":"2026-07-02T16:44:44.7213Z","end":"2026-07-02T16:44:44.91076Z","steps":["trace[1382640708] 'process raft request' (duration: 55.673848ms)","trace[1382640708] 'compare' (duration: 133.375108ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:45:12.968818Z","caller":"traceutil/trace.go:171","msg":"trace[599494047] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8877; }","duration":"160.116797ms","start":"2026-07-02T16:45:12.80865Z","end":"2026-07-02T16:45:12.968767Z","steps":["trace[599494047] 'read index received' (duration: 159.814208ms)","trace[599494047] 'applied index is now lower than readState.Index' (duration: 301.589µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:45:12.969196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.524798ms","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-07-02T16:45:12.969269Z","caller":"traceutil/trace.go:171","msg":"trace[1027366568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8576; }","duration":"160.655922ms","start":"2026-07-02T16:45:12.808602Z","end":"2026-07-02T16:45:12.969258Z","steps":["trace[1027366568] 'agreement among raft nodes before linearized reading' (duration: 160.322792ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:45:12.969697Z","caller":"traceutil/trace.go:171","msg":"trace[970763685] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"276.690856ms","start":"2026-07-02T16:45:12.692993Z","end":"2026-07-02T16:45:12.969684Z","steps":["trace[970763685] 'process raft request' (duration: 275.557504ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:45:25.96197Z","caller":"traceutil/trace.go:171","msg":"trace[1796745366] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"225.565882ms","start":"2026-07-02T16:45:25.736374Z","end":"2026-07-02T16:45:25.96194Z","steps":["trace[1796745366] 'process raft request' (duration: 225.386957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:45:42.050748Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c1.wal"} {"level":"info","ts":"2026-07-02T16:47:14.326718Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T16:47:14.332298Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T16:47:14.332411Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T16:49:28.94382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8289} {"level":"info","ts":"2026-07-02T16:49:29.019632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8289,"took":"73.660687ms","hash":3106561807,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-02T16:49:29.019709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106561807,"revision":8289,"compact-revision":6099} {"level":"warn","ts":"2026-07-02T16:51:14.840357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341777098847170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T16:51:15.109083Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.330502666s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T16:51:15.109536Z","caller":"traceutil/trace.go:171","msg":"trace[1418148337] transaction","detail":"{read_only:false; response_revision:11624; number_of_response:1; }","duration":"1.331056721s","start":"2026-07-02T16:51:13.778459Z","end":"2026-07-02T16:51:15.109515Z","steps":["trace[1418148337] 'process raft request' (duration: 1.330922677s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.10967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:13.778439Z","time spent":"1.331156604s","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12627,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:51:15.110188Z","caller":"traceutil/trace.go:171","msg":"trace[2050583958] transaction","detail":"{read_only:false; response_revision:11626; number_of_response:1; }","duration":"752.358721ms","start":"2026-07-02T16:51:14.3578Z","end":"2026-07-02T16:51:15.110159Z","steps":["trace[2050583958] 'process raft request' (duration: 752.268859ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:51:15.110253Z","caller":"traceutil/trace.go:171","msg":"trace[863745340] linearizableReadLoop","detail":"{readStateIndex:12020; appliedIndex:12016; }","duration":"770.721394ms","start":"2026-07-02T16:51:14.339521Z","end":"2026-07-02T16:51:15.110242Z","steps":["trace[863745340] 'read index received' (duration: 769.921932ms)","trace[863745340] 'applied index is now lower than readState.Index' (duration: 798.662µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:51:15.110337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.357774Z","time spent":"752.467074ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:51:15.110409Z","caller":"traceutil/trace.go:171","msg":"trace[559975347] transaction","detail":"{read_only:false; response_revision:11627; number_of_response:1; }","duration":"534.237263ms","start":"2026-07-02T16:51:14.576162Z","end":"2026-07-02T16:51:15.110399Z","steps":["trace[559975347] 'process raft request' (duration: 533.950985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.110459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.576128Z","time spent":"534.304635ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:51:15.110526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.004802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:51:15.110547Z","caller":"traceutil/trace.go:171","msg":"trace[471798791] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11627; }","duration":"771.051314ms","start":"2026-07-02T16:51:14.339489Z","end":"2026-07-02T16:51:15.11054Z","steps":["trace[471798791] 'agreement among raft nodes before linearized reading' (duration: 771.016353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.110569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.339474Z","time spent":"771.090374ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-07-02T16:51:15.110718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.180007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-02T16:51:15.11074Z","caller":"traceutil/trace.go:171","msg":"trace[1509635901] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11627; }","duration":"771.222748ms","start":"2026-07-02T16:51:14.33951Z","end":"2026-07-02T16:51:15.110733Z","steps":["trace[1509635901] 'agreement among raft nodes before linearized reading' (duration: 771.141096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.110757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.339499Z","time spent":"771.253119ms","remote":"127.0.0.1:45138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"info","ts":"2026-07-02T16:51:15.110746Z","caller":"traceutil/trace.go:171","msg":"trace[892403175] transaction","detail":"{read_only:false; response_revision:11625; number_of_response:1; }","duration":"801.586023ms","start":"2026-07-02T16:51:14.309131Z","end":"2026-07-02T16:51:15.110717Z","steps":["trace[892403175] 'process raft request' (duration: 800.813241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.110847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.309108Z","time spent":"801.686285ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:51:15.111067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.560309ms","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":"info","ts":"2026-07-02T16:51:15.111094Z","caller":"traceutil/trace.go:171","msg":"trace[2015848798] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:11627; }","duration":"154.629401ms","start":"2026-07-02T16:51:14.956455Z","end":"2026-07-02T16:51:15.111085Z","steps":["trace[2015848798] 'agreement among raft nodes before linearized reading' (duration: 154.539928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.111246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.982632ms","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-07-02T16:51:15.11127Z","caller":"traceutil/trace.go:171","msg":"trace[989133527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11627; }","duration":"277.016674ms","start":"2026-07-02T16:51:14.834247Z","end":"2026-07-02T16:51:15.111263Z","steps":["trace[989133527] 'agreement among raft nodes before linearized reading' (duration: 276.943602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.111367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.749926ms","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-07-02T16:51:15.111386Z","caller":"traceutil/trace.go:171","msg":"trace[301331088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11627; }","duration":"277.800926ms","start":"2026-07-02T16:51:14.833579Z","end":"2026-07-02T16:51:15.11138Z","steps":["trace[301331088] 'agreement among raft nodes before linearized reading' (duration: 277.746345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.111457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.39733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:51:15.111473Z","caller":"traceutil/trace.go:171","msg":"trace[1330105285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11627; }","duration":"630.414311ms","start":"2026-07-02T16:51:14.481054Z","end":"2026-07-02T16:51:15.111468Z","steps":["trace[1330105285] 'agreement among raft nodes before linearized reading' (duration: 630.38855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.111488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.480989Z","time spent":"630.494933ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T16:51:15.111637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.917402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-krm9j\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-07-02T16:51:15.111662Z","caller":"traceutil/trace.go:171","msg":"trace[626370242] range","detail":"{range_begin:/registry/pods/openstack/keepalived-krm9j; range_end:; response_count:1; response_revision:11627; }","duration":"717.942103ms","start":"2026-07-02T16:51:14.393712Z","end":"2026-07-02T16:51:15.111654Z","steps":["trace[626370242] 'agreement among raft nodes before linearized reading' (duration: 717.873721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.111682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.393658Z","time spent":"718.018125ms","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-krm9j\" "} {"level":"warn","ts":"2026-07-02T16:51:15.111895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.409472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T16:51:15.112034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.966667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:51:15.112061Z","caller":"traceutil/trace.go:171","msg":"trace[2038394682] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11627; }","duration":"767.026589ms","start":"2026-07-02T16:51:14.345024Z","end":"2026-07-02T16:51:15.112051Z","steps":["trace[2038394682] 'agreement among raft nodes before linearized reading' (duration: 766.988398ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:51:15.11205Z","caller":"traceutil/trace.go:171","msg":"trace[1068663560] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11627; }","duration":"766.583337ms","start":"2026-07-02T16:51:14.345449Z","end":"2026-07-02T16:51:15.112033Z","steps":["trace[1068663560] 'agreement among raft nodes before linearized reading' (duration: 766.386001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.112083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.345007Z","time spent":"767.070681ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-07-02T16:51:15.112105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.345438Z","time spent":"766.653459ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-07-02T16:51:15.112191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.59964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-07-02T16:51:15.112212Z","caller":"traceutil/trace.go:171","msg":"trace[2077504847] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:1; response_revision:11627; }","duration":"771.622001ms","start":"2026-07-02T16:51:14.340583Z","end":"2026-07-02T16:51:15.112205Z","steps":["trace[2077504847] 'agreement among raft nodes before linearized reading' (duration: 771.58381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.11223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.340562Z","time spent":"771.663771ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":12664,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-07-02T16:51:15.111992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.399442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:51:15.112375Z","caller":"traceutil/trace.go:171","msg":"trace[1933315925] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11627; }","duration":"772.795343ms","start":"2026-07-02T16:51:14.339563Z","end":"2026-07-02T16:51:15.112358Z","steps":["trace[1933315925] 'agreement among raft nodes before linearized reading' (duration: 772.35179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.112438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.339556Z","time spent":"772.863036ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-07-02T16:51:15.114948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.187471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-07-02T16:51:15.114987Z","caller":"traceutil/trace.go:171","msg":"trace[357837787] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:1; response_revision:11627; }","duration":"769.250924ms","start":"2026-07-02T16:51:14.345726Z","end":"2026-07-02T16:51:15.114977Z","steps":["trace[357837787] 'agreement among raft nodes before linearized reading' (duration: 766.049723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:51:15.115011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:51:14.345719Z","time spent":"769.286004ms","remote":"127.0.0.1:45210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":12664,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"info","ts":"2026-07-02T16:54:28.949606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10775} {"level":"info","ts":"2026-07-02T16:54:29.037162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10775,"took":"84.37863ms","hash":3470613714,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":27045888,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T16:54:29.037257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3470613714,"revision":10775,"compact-revision":8289} {"level":"info","ts":"2026-07-02T16:56:58.480595Z","caller":"traceutil/trace.go:171","msg":"trace[372043920] transaction","detail":"{read_only:false; response_revision:13917; number_of_response:1; }","duration":"108.247982ms","start":"2026-07-02T16:56:58.372323Z","end":"2026-07-02T16:56:58.480571Z","steps":["trace[372043920] 'process raft request' (duration: 108.104318ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:56:58.483533Z","caller":"traceutil/trace.go:171","msg":"trace[1382555866] transaction","detail":"{read_only:false; response_revision:13919; number_of_response:1; }","duration":"110.626241ms","start":"2026-07-02T16:56:58.372881Z","end":"2026-07-02T16:56:58.483507Z","steps":["trace[1382555866] 'process raft request' (duration: 110.566569ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:56:58.483583Z","caller":"traceutil/trace.go:171","msg":"trace[1139258740] transaction","detail":"{read_only:false; response_revision:13918; number_of_response:1; }","duration":"110.767444ms","start":"2026-07-02T16:56:58.372784Z","end":"2026-07-02T16:56:58.483551Z","steps":["trace[1139258740] 'process raft request' (duration: 110.504886ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:57:08.742681Z","caller":"traceutil/trace.go:171","msg":"trace[2147206050] linearizableReadLoop","detail":"{readStateIndex:14570; appliedIndex:14569; }","duration":"261.54365ms","start":"2026-07-02T16:57:08.4811Z","end":"2026-07-02T16:57:08.742643Z","steps":["trace[2147206050] 'read index received' (duration: 253.414928ms)","trace[2147206050] 'applied index is now lower than readState.Index' (duration: 8.127632ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:57:08.742761Z","caller":"traceutil/trace.go:171","msg":"trace[764584469] transaction","detail":"{read_only:false; response_revision:14091; number_of_response:1; }","duration":"270.212498ms","start":"2026-07-02T16:57:08.47251Z","end":"2026-07-02T16:57:08.742723Z","steps":["trace[764584469] 'process raft request' (duration: 261.953032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:08.742855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.725035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:57:08.742898Z","caller":"traceutil/trace.go:171","msg":"trace[935932384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14091; }","duration":"261.817207ms","start":"2026-07-02T16:57:08.481068Z","end":"2026-07-02T16:57:08.742885Z","steps":["trace[935932384] 'agreement among raft nodes before linearized reading' (duration: 261.702324ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:57:08.975894Z","caller":"traceutil/trace.go:171","msg":"trace[1139622157] transaction","detail":"{read_only:false; response_revision:14093; number_of_response:1; }","duration":"462.159768ms","start":"2026-07-02T16:57:08.513698Z","end":"2026-07-02T16:57:08.975858Z","steps":["trace[1139622157] 'process raft request' (duration: 462.061906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:08.976117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:57:08.513666Z","time spent":"462.333613ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:57:08.976113Z","caller":"traceutil/trace.go:171","msg":"trace[1262695984] linearizableReadLoop","detail":"{readStateIndex:14573; appliedIndex:14570; }","duration":"232.082878ms","start":"2026-07-02T16:57:08.744Z","end":"2026-07-02T16:57:08.976083Z","steps":["trace[1262695984] 'read index received' (duration: 218.763417ms)","trace[1262695984] 'applied index is now lower than readState.Index' (duration: 13.318411ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:57:08.976138Z","caller":"traceutil/trace.go:171","msg":"trace[2146443435] transaction","detail":"{read_only:false; response_revision:14092; number_of_response:1; }","duration":"462.552719ms","start":"2026-07-02T16:57:08.513543Z","end":"2026-07-02T16:57:08.976096Z","steps":["trace[2146443435] 'process raft request' (duration: 449.298941ms)","trace[2146443435] 'compare' (duration: 12.736103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:57:08.976259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.241372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:57:08.976293Z","caller":"traceutil/trace.go:171","msg":"trace[1281051450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14094; }","duration":"232.341215ms","start":"2026-07-02T16:57:08.743942Z","end":"2026-07-02T16:57:08.976283Z","steps":["trace[1281051450] 'agreement among raft nodes before linearized reading' (duration: 232.267323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:08.976321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:57:08.513524Z","time spent":"462.693963ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:57:08.976138Z","caller":"traceutil/trace.go:171","msg":"trace[1445909321] transaction","detail":"{read_only:false; response_revision:14094; number_of_response:1; }","duration":"461.002804ms","start":"2026-07-02T16:57:08.51511Z","end":"2026-07-02T16:57:08.976112Z","steps":["trace[1445909321] 'process raft request' (duration: 460.712796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:08.97659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:57:08.515094Z","time spent":"461.426777ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:59:28.95541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-07-02T16:59:29.030979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"73.375417ms","hash":449492303,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T16:59:29.031052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":449492303,"revision":12854,"compact-revision":10775} {"level":"info","ts":"2026-07-02T17:04:28.962268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15452} {"level":"info","ts":"2026-07-02T17:04:29.060925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15452,"took":"95.662764ms","hash":2538189321,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:04:29.060975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2538189321,"revision":15452,"compact-revision":12854} {"level":"info","ts":"2026-07-02T17:04:35.962746Z","caller":"traceutil/trace.go:171","msg":"trace[1169824621] transaction","detail":"{read_only:false; response_revision:17885; number_of_response:1; }","duration":"109.821729ms","start":"2026-07-02T17:04:35.852874Z","end":"2026-07-02T17:04:35.962695Z","steps":["trace[1169824621] 'process raft request' (duration: 98.715662ms)","trace[1169824621] 'compare' (duration: 10.805599ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:04:36.849103Z","caller":"traceutil/trace.go:171","msg":"trace[1400546672] transaction","detail":"{read_only:false; response_revision:17886; number_of_response:1; }","duration":"277.483914ms","start":"2026-07-02T17:04:36.571585Z","end":"2026-07-02T17:04:36.849069Z","steps":["trace[1400546672] 'process raft request' (duration: 277.279647ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:04:36.985882Z","caller":"traceutil/trace.go:171","msg":"trace[799737630] linearizableReadLoop","detail":"{readStateIndex:18487; appliedIndex:18485; }","duration":"324.050764ms","start":"2026-07-02T17:04:36.661813Z","end":"2026-07-02T17:04:36.985864Z","steps":["trace[799737630] 'read index received' (duration: 187.082658ms)","trace[799737630] 'applied index is now lower than readState.Index' (duration: 136.967416ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:04:36.985981Z","caller":"traceutil/trace.go:171","msg":"trace[1597316700] transaction","detail":"{read_only:false; response_revision:17887; number_of_response:1; }","duration":"333.865874ms","start":"2026-07-02T17:04:36.652078Z","end":"2026-07-02T17:04:36.985944Z","steps":["trace[1597316700] 'process raft request' (duration: 263.471142ms)","trace[1597316700] 'compare' (duration: 60.590692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:04:36.986025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.196298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"info","ts":"2026-07-02T17:04:36.986047Z","caller":"traceutil/trace.go:171","msg":"trace[1505703168] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17888; }","duration":"324.27273ms","start":"2026-07-02T17:04:36.661767Z","end":"2026-07-02T17:04:36.98604Z","steps":["trace[1505703168] 'agreement among raft nodes before linearized reading' (duration: 324.161927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:36.986067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:04:36.661747Z","time spent":"324.315962ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3321,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"warn","ts":"2026-07-02T17:04:36.986128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:04:36.652054Z","time spent":"333.992408ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:04:36.986267Z","caller":"traceutil/trace.go:171","msg":"trace[425015185] transaction","detail":"{read_only:false; response_revision:17888; number_of_response:1; }","duration":"308.491519ms","start":"2026-07-02T17:04:36.677748Z","end":"2026-07-02T17:04:36.986239Z","steps":["trace[425015185] 'process raft request' (duration: 308.064907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:36.986409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:04:36.67773Z","time spent":"308.601763ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:07:31.454459Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:07:31.464865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T17:07:31.465009Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T17:09:28.967789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17843} {"level":"info","ts":"2026-07-02T17:09:29.075527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17843,"took":"104.92577ms","hash":3598639639,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T17:09:29.075619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3598639639,"revision":17843,"compact-revision":15452} {"level":"info","ts":"2026-07-02T17:09:50.04059Z","caller":"traceutil/trace.go:171","msg":"trace[1477302941] transaction","detail":"{read_only:false; response_revision:20346; number_of_response:1; }","duration":"104.183928ms","start":"2026-07-02T17:09:49.936379Z","end":"2026-07-02T17:09:50.040563Z","steps":["trace[1477302941] 'process raft request' (duration: 104.013463ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:00.689278Z","caller":"traceutil/trace.go:171","msg":"trace[1184468321] linearizableReadLoop","detail":"{readStateIndex:21097; appliedIndex:21096; }","duration":"112.466226ms","start":"2026-07-02T17:10:00.576786Z","end":"2026-07-02T17:10:00.689252Z","steps":["trace[1184468321] 'read index received' (duration: 112.282991ms)","trace[1184468321] 'applied index is now lower than readState.Index' (duration: 182.325µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:00.689477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.659902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:10:00.689617Z","caller":"traceutil/trace.go:171","msg":"trace[1857533144] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20412; }","duration":"112.853847ms","start":"2026-07-02T17:10:00.57674Z","end":"2026-07-02T17:10:00.689594Z","steps":["trace[1857533144] 'agreement among raft nodes before linearized reading' (duration: 112.61191ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:00.689815Z","caller":"traceutil/trace.go:171","msg":"trace[670868428] transaction","detail":"{read_only:false; response_revision:20413; number_of_response:1; }","duration":"107.490934ms","start":"2026-07-02T17:10:00.582309Z","end":"2026-07-02T17:10:00.6898Z","steps":["trace[670868428] 'process raft request' (duration: 107.415582ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:00.690003Z","caller":"traceutil/trace.go:171","msg":"trace[158297438] transaction","detail":"{read_only:false; response_revision:20412; number_of_response:1; }","duration":"141.746827ms","start":"2026-07-02T17:10:00.548212Z","end":"2026-07-02T17:10:00.689959Z","steps":["trace[158297438] 'process raft request' (duration: 140.869021ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:13:55.349489Z","caller":"traceutil/trace.go:171","msg":"trace[1372292865] transaction","detail":"{read_only:false; response_revision:22851; number_of_response:1; }","duration":"201.395258ms","start":"2026-07-02T17:13:55.148065Z","end":"2026-07-02T17:13:55.34946Z","steps":["trace[1372292865] 'process raft request' (duration: 188.514078ms)","trace[1372292865] 'compare' (duration: 12.577531ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:13:55.374559Z","caller":"traceutil/trace.go:171","msg":"trace[1800972847] transaction","detail":"{read_only:false; response_revision:22852; number_of_response:1; }","duration":"124.829471ms","start":"2026-07-02T17:13:55.249708Z","end":"2026-07-02T17:13:55.374538Z","steps":["trace[1800972847] 'process raft request' (duration: 124.561023ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:13:55.374562Z","caller":"traceutil/trace.go:171","msg":"trace[1788381035] transaction","detail":"{read_only:false; response_revision:22853; number_of_response:1; }","duration":"114.048052ms","start":"2026-07-02T17:13:55.260486Z","end":"2026-07-02T17:13:55.374535Z","steps":["trace[1788381035] 'process raft request' (duration: 113.951079ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:14:28.973576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20243} {"level":"info","ts":"2026-07-02T17:14:29.078954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20243,"took":"102.632173ms","hash":2997679952,"current-db-size-bytes":57643008,"current-db-size":"58 MB","current-db-size-in-use-bytes":42655744,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-02T17:14:29.079011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2997679952,"revision":20243,"compact-revision":17843} {"level":"info","ts":"2026-07-02T17:17:30.937335Z","caller":"traceutil/trace.go:171","msg":"trace[1844929227] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"103.616146ms","start":"2026-07-02T17:17:30.833696Z","end":"2026-07-02T17:17:30.937312Z","steps":["trace[1844929227] 'process raft request' (duration: 103.432731ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:36.309708Z","caller":"traceutil/trace.go:171","msg":"trace[60652417] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"120.462889ms","start":"2026-07-02T17:17:36.189218Z","end":"2026-07-02T17:17:36.30968Z","steps":["trace[60652417] 'process raft request' (duration: 110.871235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:36.582543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.603137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:36.582669Z","caller":"traceutil/trace.go:171","msg":"trace[2043853883] linearizableReadLoop","detail":"{readStateIndex:25890; appliedIndex:25889; }","duration":"107.465088ms","start":"2026-07-02T17:17:36.475185Z","end":"2026-07-02T17:17:36.58265Z","steps":["trace[2043853883] 'read index received' (duration: 51.772µs)","trace[2043853883] 'applied index is now lower than readState.Index' (duration: 107.411756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:36.582736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.55664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:36.582766Z","caller":"traceutil/trace.go:171","msg":"trace[100354675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25077; }","duration":"107.613752ms","start":"2026-07-02T17:17:36.475143Z","end":"2026-07-02T17:17:36.582757Z","steps":["trace[100354675] 'agreement among raft nodes before linearized reading' (duration: 107.556911ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:36.582733Z","caller":"traceutil/trace.go:171","msg":"trace[25417171] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"265.75502ms","start":"2026-07-02T17:17:36.31693Z","end":"2026-07-02T17:17:36.582685Z","steps":["trace[25417171] 'process raft request' (duration: 124.883446ms)","trace[25417171] 'compare' (duration: 140.432472ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:41.153699Z","caller":"traceutil/trace.go:171","msg":"trace[1072138208] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25920; }","duration":"104.757319ms","start":"2026-07-02T17:17:41.048919Z","end":"2026-07-02T17:17:41.153677Z","steps":["trace[1072138208] 'read index received' (duration: 104.567294ms)","trace[1072138208] 'applied index is now lower than readState.Index' (duration: 188.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:41.15376Z","caller":"traceutil/trace.go:171","msg":"trace[1967846224] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"110.474243ms","start":"2026-07-02T17:17:41.043251Z","end":"2026-07-02T17:17:41.153725Z","steps":["trace[1967846224] 'process raft request' (duration: 110.258547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.154002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.072768ms","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-07-02T17:17:41.154054Z","caller":"traceutil/trace.go:171","msg":"trace[1808995986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25107; }","duration":"105.174512ms","start":"2026-07-02T17:17:41.048865Z","end":"2026-07-02T17:17:41.15404Z","steps":["trace[1808995986] 'agreement among raft nodes before linearized reading' (duration: 104.953704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.428701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.602239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:41.428891Z","caller":"traceutil/trace.go:171","msg":"trace[732193840] linearizableReadLoop","detail":"{readStateIndex:25923; appliedIndex:25921; }","duration":"105.612224ms","start":"2026-07-02T17:17:41.323263Z","end":"2026-07-02T17:17:41.428875Z","steps":["trace[732193840] 'read index received' (duration: 86.528518ms)","trace[732193840] 'applied index is now lower than readState.Index' (duration: 19.082616ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:41.428947Z","caller":"traceutil/trace.go:171","msg":"trace[931265605] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"270.855626ms","start":"2026-07-02T17:17:41.158085Z","end":"2026-07-02T17:17:41.428941Z","steps":["trace[931265605] 'process raft request' (duration: 270.713682ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:41.429076Z","caller":"traceutil/trace.go:171","msg":"trace[1190787457] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"271.118933ms","start":"2026-07-02T17:17:41.157894Z","end":"2026-07-02T17:17:41.429013Z","steps":["trace[1190787457] 'process raft request' (duration: 125.130673ms)","trace[1190787457] 'compare' (duration: 145.361041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:41.429083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.81869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:17:41.429235Z","caller":"traceutil/trace.go:171","msg":"trace[1919382338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25109; }","duration":"106.015756ms","start":"2026-07-02T17:17:41.323202Z","end":"2026-07-02T17:17:41.429217Z","steps":["trace[1919382338] 'agreement among raft nodes before linearized reading' (duration: 105.754429ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:41.579391Z","caller":"traceutil/trace.go:171","msg":"trace[162291966] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25923; }","duration":"150.442378ms","start":"2026-07-02T17:17:41.428916Z","end":"2026-07-02T17:17:41.579359Z","steps":["trace[162291966] 'read index received' (duration: 150.025906ms)","trace[162291966] 'applied index is now lower than readState.Index' (duration: 415.362µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:41.579471Z","caller":"traceutil/trace.go:171","msg":"trace[967095697] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"174.365723ms","start":"2026-07-02T17:17:41.405062Z","end":"2026-07-02T17:17:41.579428Z","steps":["trace[967095697] 'process raft request' (duration: 173.867409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.579654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.491214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T17:17:41.579694Z","caller":"traceutil/trace.go:171","msg":"trace[1120981875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25110; }","duration":"249.547236ms","start":"2026-07-02T17:17:41.330133Z","end":"2026-07-02T17:17:41.579681Z","steps":["trace[1120981875] 'agreement among raft nodes before linearized reading' (duration: 249.361311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.579765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.501579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:17:41.579826Z","caller":"traceutil/trace.go:171","msg":"trace[507578451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25110; }","duration":"253.56953ms","start":"2026-07-02T17:17:41.326238Z","end":"2026-07-02T17:17:41.579807Z","steps":["trace[507578451] 'agreement among raft nodes before linearized reading' (duration: 253.377205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.60688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.904489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:41.606977Z","caller":"traceutil/trace.go:171","msg":"trace[1034791004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25111; }","duration":"131.055423ms","start":"2026-07-02T17:17:41.475901Z","end":"2026-07-02T17:17:41.606956Z","steps":["trace[1034791004] 'agreement among raft nodes before linearized reading' (duration: 130.891188ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:41.607081Z","caller":"traceutil/trace.go:171","msg":"trace[735018091] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"173.513058ms","start":"2026-07-02T17:17:41.43355Z","end":"2026-07-02T17:17:41.607063Z","steps":["trace[735018091] 'process raft request' (duration: 173.063345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.885521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.933056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:41.885835Z","caller":"traceutil/trace.go:171","msg":"trace[1888201606] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"301.622158ms","start":"2026-07-02T17:17:41.584161Z","end":"2026-07-02T17:17:41.885783Z","steps":["trace[1888201606] 'process raft request' (duration: 301.460203ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:41.88591Z","caller":"traceutil/trace.go:171","msg":"trace[1446333413] linearizableReadLoop","detail":"{readStateIndex:25927; appliedIndex:25925; }","duration":"277.856987ms","start":"2026-07-02T17:17:41.608033Z","end":"2026-07-02T17:17:41.88589Z","steps":["trace[1446333413] 'read index received' (duration: 128.701746ms)","trace[1446333413] 'applied index is now lower than readState.Index' (duration: 149.154371ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:41.885991Z","caller":"traceutil/trace.go:171","msg":"trace[1500375383] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"149.732138ms","start":"2026-07-02T17:17:41.736251Z","end":"2026-07-02T17:17:41.885983Z","steps":["trace[1500375383] 'process raft request' (duration: 149.44313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:41.886036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:41.584142Z","time spent":"301.777202ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:17:41.886198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.161065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:41.886234Z","caller":"traceutil/trace.go:171","msg":"trace[1290346905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25115; }","duration":"278.196696ms","start":"2026-07-02T17:17:41.608027Z","end":"2026-07-02T17:17:41.886224Z","steps":["trace[1290346905] 'agreement among raft nodes before linearized reading' (duration: 278.126914ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:41.886263Z","caller":"traceutil/trace.go:171","msg":"trace[759940561] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"302.218435ms","start":"2026-07-02T17:17:41.584019Z","end":"2026-07-02T17:17:41.886237Z","steps":["trace[759940561] 'process raft request' (duration: 153.491816ms)","trace[759940561] 'compare' (duration: 147.579415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:41.886318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:41.583974Z","time spent":"302.316237ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:17:42.246332Z","caller":"traceutil/trace.go:171","msg":"trace[423897013] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"327.881488ms","start":"2026-07-02T17:17:41.918423Z","end":"2026-07-02T17:17:42.246305Z","steps":["trace[423897013] 'process raft request' (duration: 243.923805ms)","trace[423897013] 'compare' (duration: 83.84597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:42.246485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:41.918398Z","time spent":"328.005802ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:17:42.256062Z","caller":"traceutil/trace.go:171","msg":"trace[895046958] linearizableReadLoop","detail":"{readStateIndex:25931; appliedIndex:25929; }","duration":"140.625757ms","start":"2026-07-02T17:17:42.115409Z","end":"2026-07-02T17:17:42.256035Z","steps":["trace[895046958] 'read index received' (duration: 46.952104ms)","trace[895046958] 'applied index is now lower than readState.Index' (duration: 93.672413ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:42.256137Z","caller":"traceutil/trace.go:171","msg":"trace[1799922131] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"256.155446ms","start":"2026-07-02T17:17:41.999946Z","end":"2026-07-02T17:17:42.256101Z","steps":["trace[1799922131] 'process raft request' (duration: 255.916959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:42.256351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.924865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-02T17:17:42.256397Z","caller":"traceutil/trace.go:171","msg":"trace[2048305439] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:25117; }","duration":"141.032059ms","start":"2026-07-02T17:17:42.115354Z","end":"2026-07-02T17:17:42.256386Z","steps":["trace[2048305439] 'agreement among raft nodes before linearized reading' (duration: 140.777161ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:42.467111Z","caller":"traceutil/trace.go:171","msg":"trace[137464109] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25934; }","duration":"127.550112ms","start":"2026-07-02T17:17:42.339538Z","end":"2026-07-02T17:17:42.467088Z","steps":["trace[137464109] 'read index received' (duration: 126.668687ms)","trace[137464109] 'applied index is now lower than readState.Index' (duration: 880.365µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:42.467258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.705506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:42.467292Z","caller":"traceutil/trace.go:171","msg":"trace[1834370966] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25121; }","duration":"127.779559ms","start":"2026-07-02T17:17:42.339501Z","end":"2026-07-02T17:17:42.467281Z","steps":["trace[1834370966] 'agreement among raft nodes before linearized reading' (duration: 127.670435ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:42.4676Z","caller":"traceutil/trace.go:171","msg":"trace[992727678] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"131.958469ms","start":"2026-07-02T17:17:42.335625Z","end":"2026-07-02T17:17:42.467584Z","steps":["trace[992727678] 'process raft request' (duration: 131.423824ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:42.467656Z","caller":"traceutil/trace.go:171","msg":"trace[606666540] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"134.80307ms","start":"2026-07-02T17:17:42.332823Z","end":"2026-07-02T17:17:42.467626Z","steps":["trace[606666540] 'process raft request' (duration: 133.300328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:43.077441Z","caller":"traceutil/trace.go:171","msg":"trace[1338207150] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"265.470493ms","start":"2026-07-02T17:17:42.81194Z","end":"2026-07-02T17:17:43.077411Z","steps":["trace[1338207150] 'process raft request' (duration: 265.287817ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:43.407193Z","caller":"traceutil/trace.go:171","msg":"trace[1603817985] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"246.836387ms","start":"2026-07-02T17:17:43.160329Z","end":"2026-07-02T17:17:43.407166Z","steps":["trace[1603817985] 'process raft request' (duration: 234.791203ms)","trace[1603817985] 'compare' (duration: 11.757776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:43.407176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.020049ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:43.407329Z","caller":"traceutil/trace.go:171","msg":"trace[690637274] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25122; }","duration":"214.223854ms","start":"2026-07-02T17:17:43.193086Z","end":"2026-07-02T17:17:43.407309Z","steps":["trace[690637274] 'range keys from in-memory index tree' (duration: 213.998208ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:43.569951Z","caller":"traceutil/trace.go:171","msg":"trace[1301993089] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"133.072861ms","start":"2026-07-02T17:17:43.436842Z","end":"2026-07-02T17:17:43.569915Z","steps":["trace[1301993089] 'process raft request' (duration: 132.971188ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:43.57Z","caller":"traceutil/trace.go:171","msg":"trace[968429210] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"133.259506ms","start":"2026-07-02T17:17:43.436702Z","end":"2026-07-02T17:17:43.569962Z","steps":["trace[968429210] 'process raft request' (duration: 122.729594ms)","trace[968429210] 'compare' (duration: 10.229533ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:43.856568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.068707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:43.856675Z","caller":"traceutil/trace.go:171","msg":"trace[1901692961] linearizableReadLoop","detail":"{readStateIndex:25941; appliedIndex:25940; }","duration":"258.206973ms","start":"2026-07-02T17:17:43.598451Z","end":"2026-07-02T17:17:43.856658Z","steps":["trace[1901692961] 'read index received' (duration: 114.998012ms)","trace[1901692961] 'applied index is now lower than readState.Index' (duration: 143.207791ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:43.856906Z","caller":"traceutil/trace.go:171","msg":"trace[1777261488] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"272.360589ms","start":"2026-07-02T17:17:43.5845Z","end":"2026-07-02T17:17:43.856861Z","steps":["trace[1777261488] 'process raft request' (duration: 128.900411ms)","trace[1777261488] 'compare' (duration: 142.850411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:43.857065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.591335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:43.857149Z","caller":"traceutil/trace.go:171","msg":"trace[1546060836] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25126; }","duration":"258.714707ms","start":"2026-07-02T17:17:43.598415Z","end":"2026-07-02T17:17:43.857129Z","steps":["trace[1546060836] 'agreement among raft nodes before linearized reading' (duration: 258.575314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:43.857225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:43.857332Z","caller":"traceutil/trace.go:171","msg":"trace[441298274] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25126; }","duration":"139.255267ms","start":"2026-07-02T17:17:43.718051Z","end":"2026-07-02T17:17:43.857307Z","steps":["trace[441298274] 'agreement among raft nodes before linearized reading' (duration: 139.016521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:43.857586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.513971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:17:43.857638Z","caller":"traceutil/trace.go:171","msg":"trace[778560425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25126; }","duration":"245.607743ms","start":"2026-07-02T17:17:43.612016Z","end":"2026-07-02T17:17:43.857624Z","steps":["trace[778560425] 'agreement among raft nodes before linearized reading' (duration: 245.038696ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.036388Z","caller":"traceutil/trace.go:171","msg":"trace[1080988447] linearizableReadLoop","detail":"{readStateIndex:25942; appliedIndex:25941; }","duration":"146.68238ms","start":"2026-07-02T17:17:43.889684Z","end":"2026-07-02T17:17:44.036367Z","steps":["trace[1080988447] 'read index received' (duration: 146.590167ms)","trace[1080988447] 'applied index is now lower than readState.Index' (duration: 91.183µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:44.03642Z","caller":"traceutil/trace.go:171","msg":"trace[1265932889] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"174.641971ms","start":"2026-07-02T17:17:43.861742Z","end":"2026-07-02T17:17:44.036384Z","steps":["trace[1265932889] 'process raft request' (duration: 174.487016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:44.036656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:17:44.036742Z","caller":"traceutil/trace.go:171","msg":"trace[1809136753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25127; }","duration":"147.091332ms","start":"2026-07-02T17:17:43.889636Z","end":"2026-07-02T17:17:44.036727Z","steps":["trace[1809136753] 'agreement among raft nodes before linearized reading' (duration: 146.807003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:44.064507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.512277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T17:17:44.064687Z","caller":"traceutil/trace.go:171","msg":"trace[1296152036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25129; }","duration":"174.724364ms","start":"2026-07-02T17:17:43.88994Z","end":"2026-07-02T17:17:44.064665Z","steps":["trace[1296152036] 'agreement among raft nodes before linearized reading' (duration: 174.393414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.064675Z","caller":"traceutil/trace.go:171","msg":"trace[1572306716] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"173.25941ms","start":"2026-07-02T17:17:43.891377Z","end":"2026-07-02T17:17:44.064637Z","steps":["trace[1572306716] 'process raft request' (duration: 172.90129ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.064595Z","caller":"traceutil/trace.go:171","msg":"trace[2007584117] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"173.826797ms","start":"2026-07-02T17:17:43.890705Z","end":"2026-07-02T17:17:44.064532Z","steps":["trace[2007584117] 'process raft request' (duration: 173.405145ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.213249Z","caller":"traceutil/trace.go:171","msg":"trace[1333942538] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25945; }","duration":"111.973267ms","start":"2026-07-02T17:17:44.101244Z","end":"2026-07-02T17:17:44.213217Z","steps":["trace[1333942538] 'read index received' (duration: 56.279852ms)","trace[1333942538] 'applied index is now lower than readState.Index' (duration: 55.692015ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:44.213446Z","caller":"traceutil/trace.go:171","msg":"trace[428196902] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"143.440818ms","start":"2026-07-02T17:17:44.069855Z","end":"2026-07-02T17:17:44.213296Z","steps":["trace[428196902] 'process raft request' (duration: 87.616979ms)","trace[428196902] 'compare' (duration: 55.591241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:44.214006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.743828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277961"} {"level":"info","ts":"2026-07-02T17:17:44.214057Z","caller":"traceutil/trace.go:171","msg":"trace[662390148] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25131; }","duration":"112.81598ms","start":"2026-07-02T17:17:44.101227Z","end":"2026-07-02T17:17:44.214043Z","steps":["trace[662390148] 'agreement among raft nodes before linearized reading' (duration: 112.10252ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.506814Z","caller":"traceutil/trace.go:171","msg":"trace[892598325] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"255.813766ms","start":"2026-07-02T17:17:44.250978Z","end":"2026-07-02T17:17:44.506792Z","steps":["trace[892598325] 'process raft request' (duration: 255.655541ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:44.50798Z","caller":"traceutil/trace.go:171","msg":"trace[2011972461] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"233.338593ms","start":"2026-07-02T17:17:44.274619Z","end":"2026-07-02T17:17:44.507957Z","steps":["trace[2011972461] 'process raft request' (duration: 232.789267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:44.751494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.826849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:44.751646Z","caller":"traceutil/trace.go:171","msg":"trace[691552565] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25135; }","duration":"144.045595ms","start":"2026-07-02T17:17:44.607562Z","end":"2026-07-02T17:17:44.751608Z","steps":["trace[691552565] 'count revisions from in-memory index tree' (duration: 143.685665ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:45.758301Z","caller":"traceutil/trace.go:171","msg":"trace[1634612027] linearizableReadLoop","detail":"{readStateIndex:25952; appliedIndex:25951; }","duration":"183.441282ms","start":"2026-07-02T17:17:45.574838Z","end":"2026-07-02T17:17:45.758279Z","steps":["trace[1634612027] 'read index received' (duration: 183.234306ms)","trace[1634612027] 'applied index is now lower than readState.Index' (duration: 205.956µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:45.758349Z","caller":"traceutil/trace.go:171","msg":"trace[1562328701] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"267.348475ms","start":"2026-07-02T17:17:45.490976Z","end":"2026-07-02T17:17:45.758325Z","steps":["trace[1562328701] 'process raft request' (duration: 267.149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:45.75859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.734611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T17:17:45.758627Z","caller":"traceutil/trace.go:171","msg":"trace[1121922740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25137; }","duration":"183.839133ms","start":"2026-07-02T17:17:45.574778Z","end":"2026-07-02T17:17:45.758617Z","steps":["trace[1121922740] 'agreement among raft nodes before linearized reading' (duration: 183.626667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:45.758603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.68973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:45.758679Z","caller":"traceutil/trace.go:171","msg":"trace[1494555046] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25137; }","duration":"116.820055ms","start":"2026-07-02T17:17:45.641838Z","end":"2026-07-02T17:17:45.758658Z","steps":["trace[1494555046] 'agreement among raft nodes before linearized reading' (duration: 116.687491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:45.758801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.893126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:45.758914Z","caller":"traceutil/trace.go:171","msg":"trace[1649772211] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25137; }","duration":"117.080462ms","start":"2026-07-02T17:17:45.641807Z","end":"2026-07-02T17:17:45.758887Z","steps":["trace[1649772211] 'agreement among raft nodes before linearized reading' (duration: 116.882677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:45.758977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.981737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T17:17:45.759107Z","caller":"traceutil/trace.go:171","msg":"trace[1809052877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25137; }","duration":"184.226395ms","start":"2026-07-02T17:17:45.574857Z","end":"2026-07-02T17:17:45.759083Z","steps":["trace[1809052877] 'agreement among raft nodes before linearized reading' (duration: 183.773722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:46.25076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.020245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:46.250841Z","caller":"traceutil/trace.go:171","msg":"trace[1695659179] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25955; }","duration":"181.58961ms","start":"2026-07-02T17:17:46.069239Z","end":"2026-07-02T17:17:46.250829Z","steps":["trace[1695659179] 'read index received' (duration: 44.925467ms)","trace[1695659179] 'applied index is now lower than readState.Index' (duration: 136.663143ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:46.250907Z","caller":"traceutil/trace.go:171","msg":"trace[305567097] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"205.063261ms","start":"2026-07-02T17:17:46.045807Z","end":"2026-07-02T17:17:46.250871Z","steps":["trace[305567097] 'process raft request' (duration: 68.354817ms)","trace[305567097] 'compare' (duration: 135.769068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:46.250945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.716123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:17:46.25107Z","caller":"traceutil/trace.go:171","msg":"trace[1053156797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25141; }","duration":"181.840747ms","start":"2026-07-02T17:17:46.069222Z","end":"2026-07-02T17:17:46.251062Z","steps":["trace[1053156797] 'agreement among raft nodes before linearized reading' (duration: 181.653972ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:46.262719Z","caller":"traceutil/trace.go:171","msg":"trace[1140438191] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"192.609636ms","start":"2026-07-02T17:17:46.07008Z","end":"2026-07-02T17:17:46.26269Z","steps":["trace[1140438191] 'process raft request' (duration: 192.476512ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:46.262883Z","caller":"traceutil/trace.go:171","msg":"trace[306753398] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"192.353628ms","start":"2026-07-02T17:17:46.070513Z","end":"2026-07-02T17:17:46.262866Z","steps":["trace[306753398] 'process raft request' (duration: 192.116971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:46.575246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.963662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:46.575482Z","caller":"traceutil/trace.go:171","msg":"trace[1497939678] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"308.201585ms","start":"2026-07-02T17:17:46.267255Z","end":"2026-07-02T17:17:46.575457Z","steps":["trace[1497939678] 'process raft request' (duration: 162.942325ms)","trace[1497939678] 'compare' (duration: 144.695534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:46.575658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:46.267241Z","time spent":"308.377911ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:17:46.628925Z","caller":"traceutil/trace.go:171","msg":"trace[1859179745] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"116.666811ms","start":"2026-07-02T17:17:46.512244Z","end":"2026-07-02T17:17:46.628911Z","steps":["trace[1859179745] 'process raft request' (duration: 116.572938ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:46.642459Z","caller":"traceutil/trace.go:171","msg":"trace[1164272973] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"129.305122ms","start":"2026-07-02T17:17:46.513125Z","end":"2026-07-02T17:17:46.64243Z","steps":["trace[1164272973] 'process raft request' (duration: 129.102516ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:46.642705Z","caller":"traceutil/trace.go:171","msg":"trace[564292924] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"129.188379ms","start":"2026-07-02T17:17:46.513499Z","end":"2026-07-02T17:17:46.642688Z","steps":["trace[564292924] 'process raft request' (duration: 128.877671ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:46.642996Z","caller":"traceutil/trace.go:171","msg":"trace[1643321679] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"129.282812ms","start":"2026-07-02T17:17:46.513683Z","end":"2026-07-02T17:17:46.642966Z","steps":["trace[1643321679] 'process raft request' (duration: 128.931532ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:56.840295Z","caller":"traceutil/trace.go:171","msg":"trace[1236388950] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"180.501588ms","start":"2026-07-02T17:17:56.659762Z","end":"2026-07-02T17:17:56.840263Z","steps":["trace[1236388950] 'process raft request' (duration: 155.544743ms)","trace[1236388950] 'compare' (duration: 24.748679ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:56.960929Z","caller":"traceutil/trace.go:171","msg":"trace[1074990498] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"296.12824ms","start":"2026-07-02T17:17:56.664774Z","end":"2026-07-02T17:17:56.960903Z","steps":["trace[1074990498] 'process raft request' (duration: 295.890153ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:56.961203Z","caller":"traceutil/trace.go:171","msg":"trace[575947611] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"275.680333ms","start":"2026-07-02T17:17:56.685504Z","end":"2026-07-02T17:17:56.961184Z","steps":["trace[575947611] 'process raft request' (duration: 275.337744ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:56.961484Z","caller":"traceutil/trace.go:171","msg":"trace[475987520] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"275.52761ms","start":"2026-07-02T17:17:56.685946Z","end":"2026-07-02T17:17:56.961474Z","steps":["trace[475987520] 'process raft request' (duration: 275.167099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:56.961488Z","caller":"traceutil/trace.go:171","msg":"trace[323957230] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"275.176459ms","start":"2026-07-02T17:17:56.686288Z","end":"2026-07-02T17:17:56.961464Z","steps":["trace[323957230] 'process raft request' (duration: 275.097677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:57.882396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.553187ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T17:17:57.882517Z","caller":"traceutil/trace.go:171","msg":"trace[1555797655] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26102; }","duration":"200.77343ms","start":"2026-07-02T17:17:57.681725Z","end":"2026-07-02T17:17:57.882499Z","steps":["trace[1555797655] 'read index received' (duration: 25.028817ms)","trace[1555797655] 'applied index is now lower than readState.Index' (duration: 175.742903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:57.882681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.936494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:57.882742Z","caller":"traceutil/trace.go:171","msg":"trace[1769273643] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25284; }","duration":"201.034027ms","start":"2026-07-02T17:17:57.681688Z","end":"2026-07-02T17:17:57.882722Z","steps":["trace[1769273643] 'agreement among raft nodes before linearized reading' (duration: 200.925474ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.016756Z","caller":"traceutil/trace.go:171","msg":"trace[1580660555] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"129.606451ms","start":"2026-07-02T17:17:57.887122Z","end":"2026-07-02T17:17:58.016729Z","steps":["trace[1580660555] 'process raft request' (duration: 129.376455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.038581Z","caller":"traceutil/trace.go:171","msg":"trace[1478328771] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"150.848529ms","start":"2026-07-02T17:17:57.887709Z","end":"2026-07-02T17:17:58.038557Z","steps":["trace[1478328771] 'process raft request' (duration: 150.630523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:58.306318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.328183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:58.306568Z","caller":"traceutil/trace.go:171","msg":"trace[131943288] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"209.758687ms","start":"2026-07-02T17:17:58.096789Z","end":"2026-07-02T17:17:58.306548Z","steps":["trace[131943288] 'process raft request' (duration: 109.130525ms)","trace[131943288] 'compare' (duration: 100.013934ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:58.511536Z","caller":"traceutil/trace.go:171","msg":"trace[655895484] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"211.804225ms","start":"2026-07-02T17:17:58.299704Z","end":"2026-07-02T17:17:58.511508Z","steps":["trace[655895484] 'process raft request' (duration: 211.64903ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.525015Z","caller":"traceutil/trace.go:171","msg":"trace[751846122] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"223.53074ms","start":"2026-07-02T17:17:58.301461Z","end":"2026-07-02T17:17:58.524992Z","steps":["trace[751846122] 'process raft request' (duration: 223.377326ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.525003Z","caller":"traceutil/trace.go:171","msg":"trace[1709984243] linearizableReadLoop","detail":"{readStateIndex:26109; appliedIndex:26108; }","duration":"211.514346ms","start":"2026-07-02T17:17:58.313464Z","end":"2026-07-02T17:17:58.524978Z","steps":["trace[1709984243] 'read index received' (duration: 198.066742ms)","trace[1709984243] 'applied index is now lower than readState.Index' (duration: 13.446674ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:58.525212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.709842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:17:58.525267Z","caller":"traceutil/trace.go:171","msg":"trace[643016073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25290; }","duration":"211.859846ms","start":"2026-07-02T17:17:58.313397Z","end":"2026-07-02T17:17:58.525256Z","steps":["trace[643016073] 'agreement among raft nodes before linearized reading' (duration: 211.657311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:58.525382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.755409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:17:58.525451Z","caller":"traceutil/trace.go:171","msg":"trace[1048916306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25290; }","duration":"185.863302ms","start":"2026-07-02T17:17:58.339571Z","end":"2026-07-02T17:17:58.525434Z","steps":["trace[1048916306] 'agreement among raft nodes before linearized reading' (duration: 185.633195ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.750408Z","caller":"traceutil/trace.go:171","msg":"trace[1671467900] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26110; }","duration":"152.672922ms","start":"2026-07-02T17:17:58.597713Z","end":"2026-07-02T17:17:58.750386Z","steps":["trace[1671467900] 'read index received' (duration: 106.756807ms)","trace[1671467900] 'applied index is now lower than readState.Index' (duration: 45.915195ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:58.750488Z","caller":"traceutil/trace.go:171","msg":"trace[72428075] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"218.904858ms","start":"2026-07-02T17:17:58.53155Z","end":"2026-07-02T17:17:58.750455Z","steps":["trace[72428075] 'process raft request' (duration: 172.957062ms)","trace[72428075] 'compare' (duration: 45.651498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:58.750588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.335797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T17:17:58.750609Z","caller":"traceutil/trace.go:171","msg":"trace[493980138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25292; }","duration":"105.381847ms","start":"2026-07-02T17:17:58.64522Z","end":"2026-07-02T17:17:58.750602Z","steps":["trace[493980138] 'agreement among raft nodes before linearized reading' (duration: 105.275534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:58.750633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.901979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04f5b129-5576-4785-a9b2-81563a424ea6\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-02T17:17:58.750743Z","caller":"traceutil/trace.go:171","msg":"trace[1312061413] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04f5b129-5576-4785-a9b2-81563a424ea6; range_end:; response_count:1; response_revision:25292; }","duration":"153.043933ms","start":"2026-07-02T17:17:58.597678Z","end":"2026-07-02T17:17:58.750722Z","steps":["trace[1312061413] 'agreement among raft nodes before linearized reading' (duration: 152.769435ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:58.997371Z","caller":"traceutil/trace.go:171","msg":"trace[223880153] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"150.989493ms","start":"2026-07-02T17:17:58.846328Z","end":"2026-07-02T17:17:58.997318Z","steps":["trace[223880153] 'process raft request' (duration: 150.590932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.881102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.879395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:00.881211Z","caller":"traceutil/trace.go:171","msg":"trace[514465753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25306; }","duration":"126.052419ms","start":"2026-07-02T17:18:00.755133Z","end":"2026-07-02T17:18:00.881185Z","steps":["trace[514465753] 'range keys from in-memory index tree' (duration: 125.662968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.881257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.293323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04f5b129-5576-4785-a9b2-81563a424ea6\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-07-02T17:18:00.881386Z","caller":"traceutil/trace.go:171","msg":"trace[278134491] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04f5b129-5576-4785-a9b2-81563a424ea6; range_end:; response_count:1; response_revision:25306; }","duration":"121.425567ms","start":"2026-07-02T17:18:00.759913Z","end":"2026-07-02T17:18:00.881339Z","steps":["trace[278134491] 'range keys from in-memory index tree' (duration: 121.060216ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:01.000223Z","caller":"traceutil/trace.go:171","msg":"trace[325958776] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"114.790457ms","start":"2026-07-02T17:18:00.885411Z","end":"2026-07-02T17:18:01.000201Z","steps":["trace[325958776] 'process raft request' (duration: 112.485441ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.044857Z","caller":"traceutil/trace.go:171","msg":"trace[1186778645] linearizableReadLoop","detail":"{readStateIndex:26175; appliedIndex:26174; }","duration":"142.420509ms","start":"2026-07-02T17:18:04.902411Z","end":"2026-07-02T17:18:05.044832Z","steps":["trace[1186778645] 'read index received' (duration: 142.280395ms)","trace[1186778645] 'applied index is now lower than readState.Index' (duration: 139.124µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:05.044968Z","caller":"traceutil/trace.go:171","msg":"trace[1572247790] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"182.733352ms","start":"2026-07-02T17:18:04.862191Z","end":"2026-07-02T17:18:05.044924Z","steps":["trace[1572247790] 'process raft request' (duration: 182.485435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.045003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.570793ms","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:8"} {"level":"info","ts":"2026-07-02T17:18:05.045064Z","caller":"traceutil/trace.go:171","msg":"trace[1854277368] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25354; }","duration":"142.649565ms","start":"2026-07-02T17:18:04.902399Z","end":"2026-07-02T17:18:05.045049Z","steps":["trace[1854277368] 'agreement among raft nodes before linearized reading' (duration: 142.532482ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.195Z","caller":"traceutil/trace.go:171","msg":"trace[67042559] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"179.584392ms","start":"2026-07-02T17:18:05.015381Z","end":"2026-07-02T17:18:05.194965Z","steps":["trace[67042559] 'process raft request' (duration: 179.308304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.19518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.402045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-02T17:18:05.195004Z","caller":"traceutil/trace.go:171","msg":"trace[1953024098] linearizableReadLoop","detail":"{readStateIndex:26179; appliedIndex:26175; }","duration":"150.048216ms","start":"2026-07-02T17:18:05.044933Z","end":"2026-07-02T17:18:05.194982Z","steps":["trace[1953024098] 'read index received' (duration: 149.84336ms)","trace[1953024098] 'applied index is now lower than readState.Index' (duration: 203.725µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:05.195314Z","caller":"traceutil/trace.go:171","msg":"trace[2097236315] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"176.287808ms","start":"2026-07-02T17:18:05.019013Z","end":"2026-07-02T17:18:05.195301Z","steps":["trace[2097236315] 'process raft request' (duration: 175.827745ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.195266Z","caller":"traceutil/trace.go:171","msg":"trace[634397955] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25358; }","duration":"283.528218ms","start":"2026-07-02T17:18:04.911715Z","end":"2026-07-02T17:18:05.195244Z","steps":["trace[634397955] 'agreement among raft nodes before linearized reading' (duration: 283.338953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.195369Z","caller":"traceutil/trace.go:171","msg":"trace[362271248] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"175.188116ms","start":"2026-07-02T17:18:05.020129Z","end":"2026-07-02T17:18:05.195317Z","steps":["trace[362271248] 'process raft request' (duration: 174.812535ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.195423Z","caller":"traceutil/trace.go:171","msg":"trace[1722651155] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"175.808983ms","start":"2026-07-02T17:18:05.019595Z","end":"2026-07-02T17:18:05.195404Z","steps":["trace[1722651155] 'process raft request' (duration: 175.298179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.195557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.400065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:05.195628Z","caller":"traceutil/trace.go:171","msg":"trace[2116030789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25358; }","duration":"276.506178ms","start":"2026-07-02T17:18:04.919103Z","end":"2026-07-02T17:18:05.195609Z","steps":["trace[2116030789] 'agreement among raft nodes before linearized reading' (duration: 276.333532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.195965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.951417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T17:18:05.196678Z","caller":"traceutil/trace.go:171","msg":"trace[1115286772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25358; }","duration":"177.667347ms","start":"2026-07-02T17:18:05.018972Z","end":"2026-07-02T17:18:05.196639Z","steps":["trace[1115286772] 'agreement among raft nodes before linearized reading' (duration: 176.428491ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.282942Z","caller":"traceutil/trace.go:171","msg":"trace[878787675] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"231.792138ms","start":"2026-07-02T17:18:05.051121Z","end":"2026-07-02T17:18:05.282913Z","steps":["trace[878787675] 'process raft request' (duration: 231.50166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.282969Z","caller":"traceutil/trace.go:171","msg":"trace[777327952] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"230.629755ms","start":"2026-07-02T17:18:05.052316Z","end":"2026-07-02T17:18:05.282945Z","steps":["trace[777327952] 'process raft request' (duration: 230.479181ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.283031Z","caller":"traceutil/trace.go:171","msg":"trace[1875457586] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"229.646806ms","start":"2026-07-02T17:18:05.053376Z","end":"2026-07-02T17:18:05.283022Z","steps":["trace[1875457586] 'process raft request' (duration: 229.498652ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.283025Z","caller":"traceutil/trace.go:171","msg":"trace[328441022] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"229.80168ms","start":"2026-07-02T17:18:05.053182Z","end":"2026-07-02T17:18:05.282984Z","steps":["trace[328441022] 'process raft request' (duration: 229.647736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.283149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.53183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:05.284185Z","caller":"traceutil/trace.go:171","msg":"trace[554606869] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25362; }","duration":"218.61135ms","start":"2026-07-02T17:18:05.065547Z","end":"2026-07-02T17:18:05.284158Z","steps":["trace[554606869] 'agreement among raft nodes before linearized reading' (duration: 217.497319ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.538419Z","caller":"traceutil/trace.go:171","msg":"trace[163694758] linearizableReadLoop","detail":"{readStateIndex:26185; appliedIndex:26183; }","duration":"255.490086ms","start":"2026-07-02T17:18:05.282911Z","end":"2026-07-02T17:18:05.538402Z","steps":["trace[163694758] 'read index received' (duration: 255.2776ms)","trace[163694758] 'applied index is now lower than readState.Index' (duration: 211.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:05.538471Z","caller":"traceutil/trace.go:171","msg":"trace[1080613782] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"337.616068ms","start":"2026-07-02T17:18:05.200835Z","end":"2026-07-02T17:18:05.538451Z","steps":["trace[1080613782] 'process raft request' (duration: 337.539696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.53854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.526972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:05.538571Z","caller":"traceutil/trace.go:171","msg":"trace[223433015] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25364; }","duration":"332.579433ms","start":"2026-07-02T17:18:05.205975Z","end":"2026-07-02T17:18:05.538554Z","steps":["trace[223433015] 'agreement among raft nodes before linearized reading' (duration: 332.511951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.538576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:05.200809Z","time spent":"337.695811ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:05.538589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:05.205966Z","time spent":"332.617545ms","remote":"127.0.0.1:41442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:05.538636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.287015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T17:18:05.538744Z","caller":"traceutil/trace.go:171","msg":"trace[1490305479] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25364; }","duration":"244.431729ms","start":"2026-07-02T17:18:05.29427Z","end":"2026-07-02T17:18:05.538702Z","steps":["trace[1490305479] 'agreement among raft nodes before linearized reading' (duration: 244.279985ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.538806Z","caller":"traceutil/trace.go:171","msg":"trace[484058081] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"339.043719ms","start":"2026-07-02T17:18:05.199737Z","end":"2026-07-02T17:18:05.53878Z","steps":["trace[484058081] 'process raft request' (duration: 338.511303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.538781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.835066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-07-02T17:18:05.538865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:05.199722Z","time spent":"339.121411ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:05.53887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.799552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-07-02T17:18:05.538913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.081183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:18:05.538908Z","caller":"traceutil/trace.go:171","msg":"trace[579293654] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25364; }","duration":"263.019871ms","start":"2026-07-02T17:18:05.275855Z","end":"2026-07-02T17:18:05.538875Z","steps":["trace[579293654] 'agreement among raft nodes before linearized reading' (duration: 262.63717ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.538936Z","caller":"traceutil/trace.go:171","msg":"trace[1389270620] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25364; }","duration":"241.134135ms","start":"2026-07-02T17:18:05.297797Z","end":"2026-07-02T17:18:05.538931Z","steps":["trace[1389270620] 'agreement among raft nodes before linearized reading' (duration: 241.047922ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:05.53892Z","caller":"traceutil/trace.go:171","msg":"trace[800252246] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25364; }","duration":"140.877234ms","start":"2026-07-02T17:18:05.398029Z","end":"2026-07-02T17:18:05.538906Z","steps":["trace[800252246] 'agreement among raft nodes before linearized reading' (duration: 140.695109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.538925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.324544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-04f5b129-5576-4785-a9b2-81563a424ea6\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-02T17:18:05.539038Z","caller":"traceutil/trace.go:171","msg":"trace[736792634] range","detail":"{range_begin:/registry/persistentvolumes/pvc-04f5b129-5576-4785-a9b2-81563a424ea6; range_end:; response_count:1; response_revision:25364; }","duration":"340.48591ms","start":"2026-07-02T17:18:05.19852Z","end":"2026-07-02T17:18:05.539006Z","steps":["trace[736792634] 'agreement among raft nodes before linearized reading' (duration: 340.14571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:05.539111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:05.1985Z","time spent":"340.596222ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-04f5b129-5576-4785-a9b2-81563a424ea6\" "} {"level":"info","ts":"2026-07-02T17:18:17.875776Z","caller":"traceutil/trace.go:171","msg":"trace[363254126] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"284.22777ms","start":"2026-07-02T17:18:17.591506Z","end":"2026-07-02T17:18:17.875734Z","steps":["trace[363254126] 'process raft request' (duration: 222.315017ms)","trace[363254126] 'compare' (duration: 61.570393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:18.007901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.257667ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T17:18:18.09586Z","caller":"traceutil/trace.go:171","msg":"trace[1175866313] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"401.457306ms","start":"2026-07-02T17:18:17.694378Z","end":"2026-07-02T17:18:18.095836Z","steps":["trace[1175866313] 'process raft request' (duration: 401.306131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:18.095997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.694355Z","time spent":"401.57736ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:18.268456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.940249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:18.268621Z","caller":"traceutil/trace.go:171","msg":"trace[571317797] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"450.116139ms","start":"2026-07-02T17:18:17.818479Z","end":"2026-07-02T17:18:18.268596Z","steps":["trace[571317797] 'process raft request' (duration: 311.665435ms)","trace[571317797] 'compare' (duration: 137.631831ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:18.268662Z","caller":"traceutil/trace.go:171","msg":"trace[376290571] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"167.575318ms","start":"2026-07-02T17:18:18.101069Z","end":"2026-07-02T17:18:18.268644Z","steps":["trace[376290571] 'process raft request' (duration: 167.491606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:18.26873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.818449Z","time spent":"450.219632ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:18.268727Z","caller":"traceutil/trace.go:171","msg":"trace[470783137] linearizableReadLoop","detail":"{readStateIndex:26285; appliedIndex:26283; }","duration":"392.610053ms","start":"2026-07-02T17:18:17.876081Z","end":"2026-07-02T17:18:18.268691Z","steps":["trace[470783137] 'read index received' (duration: 219.622579ms)","trace[470783137] 'applied index is now lower than readState.Index' (duration: 172.983164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:18.26894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.838369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T17:18:18.268979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.762468ms","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-07-02T17:18:18.269001Z","caller":"traceutil/trace.go:171","msg":"trace[1810864657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25462; }","duration":"157.815879ms","start":"2026-07-02T17:18:18.111179Z","end":"2026-07-02T17:18:18.268995Z","steps":["trace[1810864657] 'agreement among raft nodes before linearized reading' (duration: 157.708806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.269Z","caller":"traceutil/trace.go:171","msg":"trace[726779851] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25462; }","duration":"392.938972ms","start":"2026-07-02T17:18:17.876048Z","end":"2026-07-02T17:18:18.268987Z","steps":["trace[726779851] 'agreement among raft nodes before linearized reading' (duration: 392.800818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:18.269008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.546969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T17:18:18.269035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.876031Z","time spent":"392.993853ms","remote":"127.0.0.1:47570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":38,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:18.269091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.113859ms","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-07-02T17:18:18.2692Z","caller":"traceutil/trace.go:171","msg":"trace[614931059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25462; }","duration":"157.303715ms","start":"2026-07-02T17:18:18.111877Z","end":"2026-07-02T17:18:18.269181Z","steps":["trace[614931059] 'agreement among raft nodes before linearized reading' (duration: 157.008146ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.269078Z","caller":"traceutil/trace.go:171","msg":"trace[1861008702] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25462; }","duration":"283.684933ms","start":"2026-07-02T17:18:17.985373Z","end":"2026-07-02T17:18:18.269058Z","steps":["trace[1861008702] 'agreement among raft nodes before linearized reading' (duration: 283.501968ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.465489Z","caller":"traceutil/trace.go:171","msg":"trace[123457176] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"191.633777ms","start":"2026-07-02T17:18:18.273827Z","end":"2026-07-02T17:18:18.465461Z","steps":["trace[123457176] 'process raft request' (duration: 151.598761ms)","trace[123457176] 'compare' (duration: 39.918573ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:18.728243Z","caller":"traceutil/trace.go:171","msg":"trace[1628339538] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"398.901992ms","start":"2026-07-02T17:18:18.329303Z","end":"2026-07-02T17:18:18.728205Z","steps":["trace[1628339538] 'process raft request' (duration: 373.634848ms)","trace[1628339538] 'compare' (duration: 25.010186ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:18.728282Z","caller":"traceutil/trace.go:171","msg":"trace[377548789] linearizableReadLoop","detail":"{readStateIndex:26289; appliedIndex:26287; }","duration":"385.838799ms","start":"2026-07-02T17:18:18.342374Z","end":"2026-07-02T17:18:18.728213Z","steps":["trace[377548789] 'read index received' (duration: 83.065139ms)","trace[377548789] 'applied index is now lower than readState.Index' (duration: 302.77262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:18.728417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:18.329285Z","time spent":"399.044506ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:18.728471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.043894ms","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:8"} {"level":"warn","ts":"2026-07-02T17:18:18.728533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.578092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:18.728581Z","caller":"traceutil/trace.go:171","msg":"trace[43290516] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"155.693008ms","start":"2026-07-02T17:18:18.572864Z","end":"2026-07-02T17:18:18.728557Z","steps":["trace[43290516] 'process raft request' (duration: 155.308467ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.728584Z","caller":"traceutil/trace.go:171","msg":"trace[178290630] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"137.60962ms","start":"2026-07-02T17:18:18.590926Z","end":"2026-07-02T17:18:18.728535Z","steps":["trace[178290630] 'process raft request' (duration: 137.435565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.728544Z","caller":"traceutil/trace.go:171","msg":"trace[513070409] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25466; }","duration":"386.209849ms","start":"2026-07-02T17:18:18.34232Z","end":"2026-07-02T17:18:18.72853Z","steps":["trace[513070409] 'agreement among raft nodes before linearized reading' (duration: 386.008513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:18.728596Z","caller":"traceutil/trace.go:171","msg":"trace[618555933] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:25467; }","duration":"274.686155ms","start":"2026-07-02T17:18:18.453899Z","end":"2026-07-02T17:18:18.728585Z","steps":["trace[618555933] 'agreement among raft nodes before linearized reading' (duration: 274.541001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:18.728725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:18.342302Z","time spent":"386.403545ms","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:18.728664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.792749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:18.728879Z","caller":"traceutil/trace.go:171","msg":"trace[1839145018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25467; }","duration":"253.120498ms","start":"2026-07-02T17:18:18.475751Z","end":"2026-07-02T17:18:18.728871Z","steps":["trace[1839145018] 'agreement among raft nodes before linearized reading' (duration: 252.86243ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:19.537948Z","caller":"traceutil/trace.go:171","msg":"trace[1840901710] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"289.451438ms","start":"2026-07-02T17:18:19.248462Z","end":"2026-07-02T17:18:19.537913Z","steps":["trace[1840901710] 'process raft request' (duration: 289.222661ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:19.53795Z","caller":"traceutil/trace.go:171","msg":"trace[1940214090] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"289.441777ms","start":"2026-07-02T17:18:19.248487Z","end":"2026-07-02T17:18:19.537929Z","steps":["trace[1940214090] 'process raft request' (duration: 289.348395ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:19.53811Z","caller":"traceutil/trace.go:171","msg":"trace[2074198593] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"289.50802ms","start":"2026-07-02T17:18:19.248584Z","end":"2026-07-02T17:18:19.538092Z","steps":["trace[2074198593] 'process raft request' (duration: 289.291754ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.02086Z","caller":"traceutil/trace.go:171","msg":"trace[666141464] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"281.936372ms","start":"2026-07-02T17:18:23.738895Z","end":"2026-07-02T17:18:24.020832Z","steps":["trace[666141464] 'process raft request' (duration: 281.764378ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.211359Z","caller":"traceutil/trace.go:171","msg":"trace[452688078] linearizableReadLoop","detail":"{readStateIndex:26339; appliedIndex:26337; }","duration":"305.54643ms","start":"2026-07-02T17:18:23.905794Z","end":"2026-07-02T17:18:24.211341Z","steps":["trace[452688078] 'read index received' (duration: 115.004574ms)","trace[452688078] 'applied index is now lower than readState.Index' (duration: 190.540966ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:24.2115Z","caller":"traceutil/trace.go:171","msg":"trace[353663170] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"469.602877ms","start":"2026-07-02T17:18:23.741888Z","end":"2026-07-02T17:18:24.211491Z","steps":["trace[353663170] 'process raft request' (duration: 446.529156ms)","trace[353663170] 'compare' (duration: 22.527515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:24.211583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:23.74187Z","time spent":"469.649709ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:24.211744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.961492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:24.211768Z","caller":"traceutil/trace.go:171","msg":"trace[1460387400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25514; }","duration":"306.021964ms","start":"2026-07-02T17:18:23.90574Z","end":"2026-07-02T17:18:24.211762Z","steps":["trace[1460387400] 'agreement among raft nodes before linearized reading' (duration: 305.941011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.211784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:23.905718Z","time spent":"306.063145ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T17:18:24.453322Z","caller":"traceutil/trace.go:171","msg":"trace[1261114223] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"336.484625ms","start":"2026-07-02T17:18:24.116796Z","end":"2026-07-02T17:18:24.453281Z","steps":["trace[1261114223] 'process raft request' (duration: 281.667625ms)","trace[1261114223] 'compare' (duration: 54.533932ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:24.453487Z","caller":"traceutil/trace.go:171","msg":"trace[669010607] linearizableReadLoop","detail":"{readStateIndex:26341; appliedIndex:26339; }","duration":"242.05267ms","start":"2026-07-02T17:18:24.211403Z","end":"2026-07-02T17:18:24.453456Z","steps":["trace[669010607] 'read index received' (duration: 187.083856ms)","trace[669010607] 'applied index is now lower than readState.Index' (duration: 54.967354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:24.453556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:24.116778Z","time spent":"336.650809ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:24.453556Z","caller":"traceutil/trace.go:171","msg":"trace[1125441077] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"295.233693ms","start":"2026-07-02T17:18:24.158294Z","end":"2026-07-02T17:18:24.453528Z","steps":["trace[1125441077] 'process raft request' (duration: 294.924034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.45362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.294243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:24.453642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.812983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:24.453678Z","caller":"traceutil/trace.go:171","msg":"trace[1320294959] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25516; }","duration":"396.867834ms","start":"2026-07-02T17:18:24.0568Z","end":"2026-07-02T17:18:24.453668Z","steps":["trace[1320294959] 'agreement among raft nodes before linearized reading' (duration: 396.780372ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.453681Z","caller":"traceutil/trace.go:171","msg":"trace[1823372281] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25516; }","duration":"348.415246ms","start":"2026-07-02T17:18:24.105248Z","end":"2026-07-02T17:18:24.453664Z","steps":["trace[1823372281] 'agreement among raft nodes before linearized reading' (duration: 348.274992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.453714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:24.056756Z","time spent":"396.948537ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:24.453721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:24.10523Z","time spent":"348.480368ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-07-02T17:18:24.548375Z","caller":"traceutil/trace.go:171","msg":"trace[519440445] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"258.707018ms","start":"2026-07-02T17:18:24.289631Z","end":"2026-07-02T17:18:24.548338Z","steps":["trace[519440445] 'process raft request' (duration: 258.614696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.548412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.533319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:24.548443Z","caller":"traceutil/trace.go:171","msg":"trace[1865403304] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"330.593237ms","start":"2026-07-02T17:18:24.217807Z","end":"2026-07-02T17:18:24.5484Z","steps":["trace[1865403304] 'process raft request' (duration: 330.286218ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.548491Z","caller":"traceutil/trace.go:171","msg":"trace[1980943924] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25518; }","duration":"202.656554ms","start":"2026-07-02T17:18:24.345813Z","end":"2026-07-02T17:18:24.54847Z","steps":["trace[1980943924] 'agreement among raft nodes before linearized reading' (duration: 202.5249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.548536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.689924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T17:18:24.548593Z","caller":"traceutil/trace.go:171","msg":"trace[1170783871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25518; }","duration":"231.800558ms","start":"2026-07-02T17:18:24.316777Z","end":"2026-07-02T17:18:24.548578Z","steps":["trace[1170783871] 'agreement among raft nodes before linearized reading' (duration: 231.616683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.548618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:24.217774Z","time spent":"330.74685ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:24.638624Z","caller":"traceutil/trace.go:171","msg":"trace[627917992] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"177.08312ms","start":"2026-07-02T17:18:24.461498Z","end":"2026-07-02T17:18:24.638581Z","steps":["trace[627917992] 'process raft request' (duration: 176.899135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.638695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.187915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:24.638765Z","caller":"traceutil/trace.go:171","msg":"trace[998261121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25519; }","duration":"162.301548ms","start":"2026-07-02T17:18:24.476444Z","end":"2026-07-02T17:18:24.638746Z","steps":["trace[998261121] 'agreement among raft nodes before linearized reading' (duration: 162.111472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.638832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.000871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T17:18:24.638908Z","caller":"traceutil/trace.go:171","msg":"trace[916450422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25519; }","duration":"153.109785ms","start":"2026-07-02T17:18:24.485769Z","end":"2026-07-02T17:18:24.638879Z","steps":["trace[916450422] 'agreement among raft nodes before linearized reading' (duration: 152.909529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.665123Z","caller":"traceutil/trace.go:171","msg":"trace[993173349] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"112.207653ms","start":"2026-07-02T17:18:24.552877Z","end":"2026-07-02T17:18:24.665085Z","steps":["trace[993173349] 'process raft request' (duration: 111.991557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.913066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.210126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:24.913288Z","caller":"traceutil/trace.go:171","msg":"trace[1846048062] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"270.892958ms","start":"2026-07-02T17:18:24.642369Z","end":"2026-07-02T17:18:24.913262Z","steps":["trace[1846048062] 'process raft request' (duration: 138.378433ms)","trace[1846048062] 'compare' (duration: 131.913008ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:24.913498Z","caller":"traceutil/trace.go:171","msg":"trace[1862784729] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"160.626709ms","start":"2026-07-02T17:18:24.752846Z","end":"2026-07-02T17:18:24.913472Z","steps":["trace[1862784729] 'process raft request' (duration: 160.557927ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.91365Z","caller":"traceutil/trace.go:171","msg":"trace[88565690] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"164.406407ms","start":"2026-07-02T17:18:24.74923Z","end":"2026-07-02T17:18:24.913636Z","steps":["trace[88565690] 'process raft request' (duration: 164.034567ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:24.913654Z","caller":"traceutil/trace.go:171","msg":"trace[1189311232] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26345; }","duration":"248.598089ms","start":"2026-07-02T17:18:24.665031Z","end":"2026-07-02T17:18:24.913629Z","steps":["trace[1189311232] 'read index received' (duration: 115.794847ms)","trace[1189311232] 'applied index is now lower than readState.Index' (duration: 132.800252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:24.913942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.106611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-02T17:18:24.914034Z","caller":"traceutil/trace.go:171","msg":"trace[2000540518] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25523; }","duration":"265.209934ms","start":"2026-07-02T17:18:24.648801Z","end":"2026-07-02T17:18:24.914011Z","steps":["trace[2000540518] 'agreement among raft nodes before linearized reading' (duration: 264.927716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:24.913942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.526217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:18:24.914136Z","caller":"traceutil/trace.go:171","msg":"trace[540018179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25523; }","duration":"145.759064ms","start":"2026-07-02T17:18:24.768359Z","end":"2026-07-02T17:18:24.914118Z","steps":["trace[540018179] 'agreement among raft nodes before linearized reading' (duration: 145.418344ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:32.857776Z","caller":"traceutil/trace.go:171","msg":"trace[1630861257] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"109.464454ms","start":"2026-07-02T17:18:32.748271Z","end":"2026-07-02T17:18:32.857735Z","steps":["trace[1630861257] 'process raft request' (duration: 70.242071ms)","trace[1630861257] 'compare' (duration: 38.919055ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:34.699554Z","caller":"traceutil/trace.go:171","msg":"trace[270467201] linearizableReadLoop","detail":"{readStateIndex:26420; appliedIndex:26419; }","duration":"107.789666ms","start":"2026-07-02T17:18:34.591742Z","end":"2026-07-02T17:18:34.699532Z","steps":["trace[270467201] 'read index received' (duration: 107.610551ms)","trace[270467201] 'applied index is now lower than readState.Index' (duration: 177.775µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:34.699625Z","caller":"traceutil/trace.go:171","msg":"trace[871702524] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"120.025186ms","start":"2026-07-02T17:18:34.579568Z","end":"2026-07-02T17:18:34.699593Z","steps":["trace[871702524] 'process raft request' (duration: 119.80507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:34.699744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.980211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:34.699776Z","caller":"traceutil/trace.go:171","msg":"trace[1042193686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25593; }","duration":"108.067935ms","start":"2026-07-02T17:18:34.591698Z","end":"2026-07-02T17:18:34.699766Z","steps":["trace[1042193686] 'agreement among raft nodes before linearized reading' (duration: 107.92545ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:50.847561Z","caller":"traceutil/trace.go:171","msg":"trace[606357971] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"213.190675ms","start":"2026-07-02T17:18:50.634355Z","end":"2026-07-02T17:18:50.847546Z","steps":["trace[606357971] 'process raft request' (duration: 212.973439ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:50.847545Z","caller":"traceutil/trace.go:171","msg":"trace[494698346] linearizableReadLoop","detail":"{readStateIndex:26536; appliedIndex:26535; }","duration":"191.569506ms","start":"2026-07-02T17:18:50.655956Z","end":"2026-07-02T17:18:50.847526Z","steps":["trace[494698346] 'read index received' (duration: 191.458093ms)","trace[494698346] 'applied index is now lower than readState.Index' (duration: 110.803µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:50.847631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.669908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T17:18:50.84766Z","caller":"traceutil/trace.go:171","msg":"trace[937945210] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25706; }","duration":"191.767992ms","start":"2026-07-02T17:18:50.655884Z","end":"2026-07-02T17:18:50.847652Z","steps":["trace[937945210] 'agreement among raft nodes before linearized reading' (duration: 191.69815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:50.848225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.11713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-02T17:18:50.848282Z","caller":"traceutil/trace.go:171","msg":"trace[902300126] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25707; }","duration":"163.184052ms","start":"2026-07-02T17:18:50.685081Z","end":"2026-07-02T17:18:50.848265Z","steps":["trace[902300126] 'agreement among raft nodes before linearized reading' (duration: 163.023958ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:52.373383Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067ba.wal"} {"level":"info","ts":"2026-07-02T17:18:55.937736Z","caller":"traceutil/trace.go:171","msg":"trace[566094494] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"268.564801ms","start":"2026-07-02T17:18:55.669154Z","end":"2026-07-02T17:18:55.937719Z","steps":["trace[566094494] 'process raft request' (duration: 182.412503ms)","trace[566094494] 'compare' (duration: 85.634183ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:55.937867Z","caller":"traceutil/trace.go:171","msg":"trace[97323154] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"268.58181ms","start":"2026-07-02T17:18:55.669273Z","end":"2026-07-02T17:18:55.937855Z","steps":["trace[97323154] 'process raft request' (duration: 268.116747ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:55.937934Z","caller":"traceutil/trace.go:171","msg":"trace[1316600706] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"129.996794ms","start":"2026-07-02T17:18:55.807933Z","end":"2026-07-02T17:18:55.937929Z","steps":["trace[1316600706] 'process raft request' (duration: 129.498799ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.015139Z","caller":"traceutil/trace.go:171","msg":"trace[2062822966] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"147.648087ms","start":"2026-07-02T17:18:56.867474Z","end":"2026-07-02T17:18:57.015123Z","steps":["trace[2062822966] 'process raft request' (duration: 147.524214ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.040858Z","caller":"traceutil/trace.go:171","msg":"trace[719598403] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26595; }","duration":"163.573764ms","start":"2026-07-02T17:18:56.877264Z","end":"2026-07-02T17:18:57.040838Z","steps":["trace[719598403] 'read index received' (duration: 137.865228ms)","trace[719598403] 'applied index is now lower than readState.Index' (duration: 25.707885ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:57.040933Z","caller":"traceutil/trace.go:171","msg":"trace[363620409] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"172.254712ms","start":"2026-07-02T17:18:56.86865Z","end":"2026-07-02T17:18:57.040904Z","steps":["trace[363620409] 'process raft request' (duration: 172.067816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:57.041056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.77816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:57.041088Z","caller":"traceutil/trace.go:171","msg":"trace[1537289740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25765; }","duration":"163.879903ms","start":"2026-07-02T17:18:56.877199Z","end":"2026-07-02T17:18:57.041079Z","steps":["trace[1537289740] 'agreement among raft nodes before linearized reading' (duration: 163.727319ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.191476Z","caller":"traceutil/trace.go:171","msg":"trace[1278384787] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"146.30308ms","start":"2026-07-02T17:18:57.045153Z","end":"2026-07-02T17:18:57.191456Z","steps":["trace[1278384787] 'process raft request' (duration: 146.268389ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.191655Z","caller":"traceutil/trace.go:171","msg":"trace[398272532] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"163.182133ms","start":"2026-07-02T17:18:57.028451Z","end":"2026-07-02T17:18:57.191633Z","steps":["trace[398272532] 'process raft request' (duration: 123.374173ms)","trace[398272532] 'compare' (duration: 39.418509ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:57.191684Z","caller":"traceutil/trace.go:171","msg":"trace[1247643186] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"163.222824ms","start":"2026-07-02T17:18:57.028453Z","end":"2026-07-02T17:18:57.191676Z","steps":["trace[1247643186] 'process raft request' (duration: 162.919645ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.19175Z","caller":"traceutil/trace.go:171","msg":"trace[1260993091] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26596; }","duration":"150.836069ms","start":"2026-07-02T17:18:57.040908Z","end":"2026-07-02T17:18:57.191744Z","steps":["trace[1260993091] 'read index received' (duration: 110.934056ms)","trace[1260993091] 'applied index is now lower than readState.Index' (duration: 39.901373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:57.191929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.247183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-07-02T17:18:57.191958Z","caller":"traceutil/trace.go:171","msg":"trace[2109941316] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:25768; }","duration":"200.318206ms","start":"2026-07-02T17:18:56.991632Z","end":"2026-07-02T17:18:57.19195Z","steps":["trace[2109941316] 'agreement among raft nodes before linearized reading' (duration: 200.187692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:57.192115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.014077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T17:18:57.192199Z","caller":"traceutil/trace.go:171","msg":"trace[1240275423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25768; }","duration":"214.134232ms","start":"2026-07-02T17:18:56.97803Z","end":"2026-07-02T17:18:57.192164Z","steps":["trace[1240275423] 'agreement among raft nodes before linearized reading' (duration: 213.869333ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.337354Z","caller":"traceutil/trace.go:171","msg":"trace[1267627381] linearizableReadLoop","detail":"{readStateIndex:26600; appliedIndex:26599; }","duration":"145.551668ms","start":"2026-07-02T17:18:57.191772Z","end":"2026-07-02T17:18:57.337324Z","steps":["trace[1267627381] 'read index received' (duration: 61.08914ms)","trace[1267627381] 'applied index is now lower than readState.Index' (duration: 84.461308ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:57.337793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.502628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T17:18:57.337816Z","caller":"traceutil/trace.go:171","msg":"trace[531383532] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"140.75998ms","start":"2026-07-02T17:18:57.197027Z","end":"2026-07-02T17:18:57.337787Z","steps":["trace[531383532] 'process raft request' (duration: 140.187324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:57.337829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.175512ms","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-07-02T17:18:57.337854Z","caller":"traceutil/trace.go:171","msg":"trace[1993909676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25768; }","duration":"232.618682ms","start":"2026-07-02T17:18:57.105218Z","end":"2026-07-02T17:18:57.337837Z","steps":["trace[1993909676] 'agreement among raft nodes before linearized reading' (duration: 232.419665ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.338198Z","caller":"traceutil/trace.go:171","msg":"trace[214269412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25768; }","duration":"288.292316ms","start":"2026-07-02T17:18:57.049604Z","end":"2026-07-02T17:18:57.337897Z","steps":["trace[214269412] 'agreement among raft nodes before linearized reading' (duration: 288.057988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:57.419866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.606039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-jx4xx\" ","response":"range_response_count:1 size:4903"} {"level":"info","ts":"2026-07-02T17:18:57.419983Z","caller":"traceutil/trace.go:171","msg":"trace[340006122] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-jx4xx; range_end:; response_count:1; response_revision:25769; }","duration":"226.789724ms","start":"2026-07-02T17:18:57.193149Z","end":"2026-07-02T17:18:57.419938Z","steps":["trace[340006122] 'agreement among raft nodes before linearized reading' (duration: 144.63494ms)","trace[340006122] 'range keys from in-memory index tree' (duration: 81.847774ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:57.450936Z","caller":"traceutil/trace.go:171","msg":"trace[211143394] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"109.258729ms","start":"2026-07-02T17:18:57.341653Z","end":"2026-07-02T17:18:57.450912Z","steps":["trace[211143394] 'process raft request' (duration: 109.085514ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:57.509773Z","caller":"traceutil/trace.go:171","msg":"trace[1947750426] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"167.426044ms","start":"2026-07-02T17:18:57.342321Z","end":"2026-07-02T17:18:57.509747Z","steps":["trace[1947750426] 'process raft request' (duration: 167.240189ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:58.077214Z","caller":"traceutil/trace.go:171","msg":"trace[1212108746] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"131.880996ms","start":"2026-07-02T17:18:57.945298Z","end":"2026-07-02T17:18:58.077179Z","steps":["trace[1212108746] 'process raft request' (duration: 131.65941ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:59.240051Z","caller":"traceutil/trace.go:171","msg":"trace[619627799] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"101.076926ms","start":"2026-07-02T17:18:59.138896Z","end":"2026-07-02T17:18:59.239973Z","steps":["trace[619627799] 'process raft request' (duration: 28.079405ms)","trace[619627799] 'compare' (duration: 72.724702ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:59.396267Z","caller":"traceutil/trace.go:171","msg":"trace[1552817801] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"199.08714ms","start":"2026-07-02T17:18:59.197148Z","end":"2026-07-02T17:18:59.396235Z","steps":["trace[1552817801] 'process raft request' (duration: 191.876315ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:59.396478Z","caller":"traceutil/trace.go:171","msg":"trace[509510289] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"199.227935ms","start":"2026-07-02T17:18:59.197227Z","end":"2026-07-02T17:18:59.396455Z","steps":["trace[509510289] 'process raft request' (duration: 198.906625ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:59.396472Z","caller":"traceutil/trace.go:171","msg":"trace[1193888849] linearizableReadLoop","detail":"{readStateIndex:26613; appliedIndex:26611; }","duration":"156.499591ms","start":"2026-07-02T17:18:59.239932Z","end":"2026-07-02T17:18:59.396432Z","steps":["trace[1193888849] 'read index received' (duration: 149.255524ms)","trace[1193888849] 'applied index is now lower than readState.Index' (duration: 7.242837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:59.39668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.448277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:59.396733Z","caller":"traceutil/trace.go:171","msg":"trace[760659040] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25781; }","duration":"150.54487ms","start":"2026-07-02T17:18:59.246173Z","end":"2026-07-02T17:18:59.396718Z","steps":["trace[760659040] 'agreement among raft nodes before linearized reading' (duration: 150.433737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:59.396656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.164906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:59.396835Z","caller":"traceutil/trace.go:171","msg":"trace[462848852] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25781; }","duration":"240.390473ms","start":"2026-07-02T17:18:59.15642Z","end":"2026-07-02T17:18:59.396811Z","steps":["trace[462848852] 'agreement among raft nodes before linearized reading' (duration: 240.153036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:59.397352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.950835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T17:18:59.397398Z","caller":"traceutil/trace.go:171","msg":"trace[1277319738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25781; }","duration":"201.14821ms","start":"2026-07-02T17:18:59.196238Z","end":"2026-07-02T17:18:59.397387Z","steps":["trace[1277319738] 'agreement among raft nodes before linearized reading' (duration: 200.283484ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:28.979174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23171} {"level":"info","ts":"2026-07-02T17:19:29.110291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23171,"took":"126.824682ms","hash":1985774281,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":37281792,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-02T17:19:29.110348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1985774281,"revision":23171,"compact-revision":20243} {"level":"info","ts":"2026-07-02T17:22:14.66827Z","caller":"traceutil/trace.go:171","msg":"trace[1316196263] transaction","detail":"{read_only:false; response_revision:27759; number_of_response:1; }","duration":"130.773465ms","start":"2026-07-02T17:22:14.537464Z","end":"2026-07-02T17:22:14.668238Z","steps":["trace[1316196263] 'process raft request' (duration: 121.606792ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:24:28.985958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26166} {"level":"info","ts":"2026-07-02T17:24:29.137372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26166,"took":"146.843115ms","hash":217022793,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":35160064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T17:24:29.137448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":217022793,"revision":26166,"compact-revision":23171} {"level":"info","ts":"2026-07-02T17:24:58.336374Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:24:58.342954Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T17:24:58.343098Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T17:29:28.99285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28869} {"level":"info","ts":"2026-07-02T17:29:29.130955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28869,"took":"133.719465ms","hash":4149436915,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":30728192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T17:29:29.131038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4149436915,"revision":28869,"compact-revision":26166} {"level":"info","ts":"2026-07-02T17:34:28.999642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31092} {"level":"info","ts":"2026-07-02T17:34:29.124497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31092,"took":"120.575189ms","hash":2811119448,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":30777344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T17:34:29.124558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2811119448,"revision":31092,"compact-revision":28869} {"level":"info","ts":"2026-07-02T17:39:29.011215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33358} {"level":"info","ts":"2026-07-02T17:39:29.160882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33358,"took":"146.689279ms","hash":4234321100,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":32149504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:39:29.160946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234321100,"revision":33358,"compact-revision":31092} {"level":"info","ts":"2026-07-02T17:44:29.017602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35652} {"level":"info","ts":"2026-07-02T17:44:29.166531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35652,"took":"144.484866ms","hash":1859497835,"current-db-size-bytes":60596224,"current-db-size":"61 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:44:29.166711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1859497835,"revision":35652,"compact-revision":33358} {"level":"info","ts":"2026-07-02T17:46:24.482525Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:46:24.489036Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T17:46:24.489158Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-02T17:49:29.023241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37884} {"level":"info","ts":"2026-07-02T17:49:29.136054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37884,"took":"108.01049ms","hash":3457578096,"current-db-size-bytes":60596224,"current-db-size":"61 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:49:29.136111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3457578096,"revision":37884,"compact-revision":35652}