{"level":"warn","ts":"2026-06-10T14:58:02.281186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T14:58:02.281316Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-10T14:58:02.281413Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T14:58:02.281431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-10T14:58:02.281487Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T14:58:02.282367Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-06-10T14:58:02.282484Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-10T14:58:02.286766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.982401ms"} {"level":"info","ts":"2026-06-10T14:58:02.291656Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-06-10T14:58:02.291741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T14:58:02.291772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-06-10T14:58:02.291784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T14:58:02.291799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-06-10T14:58:02.291844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-06-10T14:58:02.300378Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T14:58:02.301959Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T14:58:02.305121Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-10T14:58:02.307745Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T14:58:02.308994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T14:58:02.309026Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T14:58:02.309125Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T14:58:02.30915Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T14:58:02.310241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-06-10T14:58:02.310692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-10T14:58:02.315452Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T14:58:02.31554Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-10T14:58:02.315587Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-10T14:58:02.315732Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T14:58:02.315782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T14:58:02.492516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T14:58:02.492628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T14:58:02.492685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-06-10T14:58:02.492704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-06-10T14:58:02.492712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-10T14:58:02.492724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-06-10T14:58:02.492735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-10T14:58:02.493708Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:02.494854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T14:58:02.494852Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T14:58:02.494914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T14:58:02.495147Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T14:58:02.495177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T14:58:02.495339Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:02.495479Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:02.495526Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T14:58:02.497583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T14:58:02.498201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-06-10T14:58:25.824122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.212129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T14:58:25.824236Z","caller":"traceutil/trace.go:171","msg":"trace[943937981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"155.345052ms","start":"2026-06-10T14:58:25.668873Z","end":"2026-06-10T14:58:25.824218Z","steps":["trace[943937981] 'range keys from in-memory index tree' (duration: 155.142938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:58:46.71461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.347521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T14:58:46.714704Z","caller":"traceutil/trace.go:171","msg":"trace[336841846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"101.503054ms","start":"2026-06-10T14:58:46.613179Z","end":"2026-06-10T14:58:46.714682Z","steps":["trace[336841846] 'range keys from in-memory index tree' (duration: 101.112515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:58:46.962889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.269724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T14:58:46.962988Z","caller":"traceutil/trace.go:171","msg":"trace[1555106897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"137.425786ms","start":"2026-06-10T14:58:46.82554Z","end":"2026-06-10T14:58:46.962966Z","steps":["trace[1555106897] 'range keys from in-memory index tree' (duration: 137.103361ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:58:47.116694Z","caller":"traceutil/trace.go:171","msg":"trace[491194716] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"250.21778ms","start":"2026-06-10T14:58:46.866453Z","end":"2026-06-10T14:58:47.116671Z","steps":["trace[491194716] 'process raft request' (duration: 249.584817ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:58:47.131963Z","caller":"traceutil/trace.go:171","msg":"trace[1170354745] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"164.01357ms","start":"2026-06-10T14:58:46.967917Z","end":"2026-06-10T14:58:47.13193Z","steps":["trace[1170354745] 'process raft request' (duration: 163.826135ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:58:47.442708Z","caller":"traceutil/trace.go:171","msg":"trace[953712251] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"195.249357ms","start":"2026-06-10T14:58:47.247421Z","end":"2026-06-10T14:58:47.44267Z","steps":["trace[953712251] 'read index received' (duration: 194.964421ms)","trace[953712251] 'applied index is now lower than readState.Index' (duration: 283.026µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T14:58:47.442885Z","caller":"traceutil/trace.go:171","msg":"trace[1458922605] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"264.540932ms","start":"2026-06-10T14:58:47.178289Z","end":"2026-06-10T14:58:47.44283Z","steps":["trace[1458922605] 'process raft request' (duration: 264.140674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T14:58:47.443023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.576205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dcxb9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-10T14:58:47.443083Z","caller":"traceutil/trace.go:171","msg":"trace[403333835] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dcxb9; range_end:; response_count:1; response_revision:638; }","duration":"195.730218ms","start":"2026-06-10T14:58:47.247338Z","end":"2026-06-10T14:58:47.443068Z","steps":["trace[403333835] 'agreement among raft nodes before linearized reading' (duration: 195.525203ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:58:47.637547Z","caller":"traceutil/trace.go:171","msg":"trace[1392637174] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"176.319251ms","start":"2026-06-10T14:58:47.461201Z","end":"2026-06-10T14:58:47.63752Z","steps":["trace[1392637174] 'process raft request' (duration: 166.725466ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T14:59:30.695901Z","caller":"traceutil/trace.go:171","msg":"trace[640397780] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"104.329931ms","start":"2026-06-10T14:59:30.591529Z","end":"2026-06-10T14:59:30.695859Z","steps":["trace[640397780] 'process raft request' (duration: 104.102596ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:31.066256Z","caller":"traceutil/trace.go:171","msg":"trace[1604185364] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"126.224818ms","start":"2026-06-10T15:00:30.940006Z","end":"2026-06-10T15:00:31.06623Z","steps":["trace[1604185364] 'process raft request' (duration: 126.081965ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:31.391414Z","caller":"traceutil/trace.go:171","msg":"trace[237592996] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"153.526599ms","start":"2026-06-10T15:00:31.237858Z","end":"2026-06-10T15:00:31.391385Z","steps":["trace[237592996] 'read index received' (duration: 153.310024ms)","trace[237592996] 'applied index is now lower than readState.Index' (duration: 215.495µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:00:31.391475Z","caller":"traceutil/trace.go:171","msg":"trace[920912394] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"203.882531ms","start":"2026-06-10T15:00:31.187559Z","end":"2026-06-10T15:00:31.391442Z","steps":["trace[920912394] 'process raft request' (duration: 203.631896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:00:31.39164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.758833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-75c08e9b-c417-4eac-a8da-418928a3a5eb\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-10T15:00:31.391686Z","caller":"traceutil/trace.go:171","msg":"trace[1339906502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-75c08e9b-c417-4eac-a8da-418928a3a5eb; range_end:; response_count:1; response_revision:1931; }","duration":"153.863926ms","start":"2026-06-10T15:00:31.23781Z","end":"2026-06-10T15:00:31.391674Z","steps":["trace[1339906502] 'agreement among raft nodes before linearized reading' (duration: 153.685852ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:00:31.514489Z","caller":"traceutil/trace.go:171","msg":"trace[811430007] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"158.198575ms","start":"2026-06-10T15:00:31.356256Z","end":"2026-06-10T15:00:31.514455Z","steps":["trace[811430007] 'process raft request' (duration: 144.517334ms)","trace[811430007] 'compare' (duration: 13.390515ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:01:15.442457Z","caller":"traceutil/trace.go:171","msg":"trace[1436736142] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"172.523428ms","start":"2026-06-10T15:01:15.269905Z","end":"2026-06-10T15:01:15.442428Z","steps":["trace[1436736142] 'process raft request' (duration: 172.318614ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:15.786433Z","caller":"traceutil/trace.go:171","msg":"trace[120530680] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"100.151993ms","start":"2026-06-10T15:01:15.686255Z","end":"2026-06-10T15:01:15.786407Z","steps":["trace[120530680] 'read index received' (duration: 35.459657ms)","trace[120530680] 'applied index is now lower than readState.Index' (duration: 64.690796ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:01:15.7866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.681586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:01:15.786664Z","caller":"traceutil/trace.go:171","msg":"trace[786477112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2230; }","duration":"120.781818ms","start":"2026-06-10T15:01:15.665861Z","end":"2026-06-10T15:01:15.786643Z","steps":["trace[786477112] 'agreement among raft nodes before linearized reading' (duration: 120.677946ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:15.786647Z","caller":"traceutil/trace.go:171","msg":"trace[2121784349] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"106.452303ms","start":"2026-06-10T15:01:15.680067Z","end":"2026-06-10T15:01:15.786519Z","steps":["trace[2121784349] 'process raft request' (duration: 41.52931ms)","trace[2121784349] 'compare' (duration: 64.688007ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:01:15.806169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.00906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T15:01:15.806338Z","caller":"traceutil/trace.go:171","msg":"trace[2145875628] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2231; }","duration":"117.174413ms","start":"2026-06-10T15:01:15.689081Z","end":"2026-06-10T15:01:15.806256Z","steps":["trace[2145875628] 'agreement among raft nodes before linearized reading' (duration: 116.878366ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:21.941557Z","caller":"traceutil/trace.go:171","msg":"trace[642477521] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"136.491515ms","start":"2026-06-10T15:01:21.80504Z","end":"2026-06-10T15:01:21.941531Z","steps":["trace[642477521] 'process raft request' (duration: 136.325711ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:21.945661Z","caller":"traceutil/trace.go:171","msg":"trace[168022715] linearizableReadLoop","detail":"{readStateIndex:2351; appliedIndex:2349; }","duration":"108.45289ms","start":"2026-06-10T15:01:21.837177Z","end":"2026-06-10T15:01:21.945629Z","steps":["trace[168022715] 'read index received' (duration: 104.213914ms)","trace[168022715] 'applied index is now lower than readState.Index' (duration: 4.237836ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:01:21.94575Z","caller":"traceutil/trace.go:171","msg":"trace[573690753] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"120.411135ms","start":"2026-06-10T15:01:21.825302Z","end":"2026-06-10T15:01:21.945713Z","steps":["trace[573690753] 'process raft request' (duration: 120.18298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:01:21.945933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.728965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T15:01:21.945992Z","caller":"traceutil/trace.go:171","msg":"trace[2014559475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2261; }","duration":"108.840018ms","start":"2026-06-10T15:01:21.83714Z","end":"2026-06-10T15:01:21.94598Z","steps":["trace[2014559475] 'agreement among raft nodes before linearized reading' (duration: 108.624224ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:27.503576Z","caller":"traceutil/trace.go:171","msg":"trace[780728678] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"132.585864ms","start":"2026-06-10T15:01:27.370961Z","end":"2026-06-10T15:01:27.503546Z","steps":["trace[780728678] 'process raft request' (duration: 132.395371ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:27.749641Z","caller":"traceutil/trace.go:171","msg":"trace[492549019] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"234.040312ms","start":"2026-06-10T15:01:27.515566Z","end":"2026-06-10T15:01:27.749607Z","steps":["trace[492549019] 'process raft request' (duration: 233.828998ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:27.778616Z","caller":"traceutil/trace.go:171","msg":"trace[1181572971] linearizableReadLoop","detail":"{readStateIndex:2384; appliedIndex:2382; }","duration":"109.698946ms","start":"2026-06-10T15:01:27.668893Z","end":"2026-06-10T15:01:27.778592Z","steps":["trace[1181572971] 'read index received' (duration: 80.495638ms)","trace[1181572971] 'applied index is now lower than readState.Index' (duration: 29.202338ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:01:27.77874Z","caller":"traceutil/trace.go:171","msg":"trace[1150856626] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"228.65861ms","start":"2026-06-10T15:01:27.550041Z","end":"2026-06-10T15:01:27.7787Z","steps":["trace[1150856626] 'process raft request' (duration: 228.425775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:01:27.778772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.880059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:01:27.778825Z","caller":"traceutil/trace.go:171","msg":"trace[1076684440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2293; }","duration":"110.003761ms","start":"2026-06-10T15:01:27.668811Z","end":"2026-06-10T15:01:27.778814Z","steps":["trace[1076684440] 'agreement among raft nodes before linearized reading' (duration: 109.907479ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:01:27.888939Z","caller":"traceutil/trace.go:171","msg":"trace[466306467] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"105.619763ms","start":"2026-06-10T15:01:27.78329Z","end":"2026-06-10T15:01:27.88891Z","steps":["trace[466306467] 'process raft request' (duration: 78.396585ms)","trace[466306467] 'compare' (duration: 27.098385ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:02:46.188764Z","caller":"traceutil/trace.go:171","msg":"trace[1881281249] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"120.656499ms","start":"2026-06-10T15:02:46.068083Z","end":"2026-06-10T15:02:46.188739Z","steps":["trace[1881281249] 'process raft request' (duration: 120.435714ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:02:46.377095Z","caller":"traceutil/trace.go:171","msg":"trace[1451403932] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"140.366054ms","start":"2026-06-10T15:02:46.236694Z","end":"2026-06-10T15:02:46.37706Z","steps":["trace[1451403932] 'process raft request' (duration: 111.020851ms)","trace[1451403932] 'compare' (duration: 29.22145ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:02:46.384205Z","caller":"traceutil/trace.go:171","msg":"trace[792280316] linearizableReadLoop","detail":"{readStateIndex:3027; appliedIndex:3025; }","duration":"108.5183ms","start":"2026-06-10T15:02:46.27566Z","end":"2026-06-10T15:02:46.384179Z","steps":["trace[792280316] 'read index received' (duration: 71.99186ms)","trace[792280316] 'applied index is now lower than readState.Index' (duration: 36.52542ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:02:46.384354Z","caller":"traceutil/trace.go:171","msg":"trace[888700275] transaction","detail":"{read_only:false; response_revision:2913; number_of_response:1; }","duration":"137.318081ms","start":"2026-06-10T15:02:46.246998Z","end":"2026-06-10T15:02:46.384316Z","steps":["trace[888700275] 'process raft request' (duration: 137.028685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:02:46.384488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.806616ms","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-06-10T15:02:46.384532Z","caller":"traceutil/trace.go:171","msg":"trace[1628310030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2913; }","duration":"108.903127ms","start":"2026-06-10T15:02:46.275619Z","end":"2026-06-10T15:02:46.384522Z","steps":["trace[1628310030] 'agreement among raft nodes before linearized reading' (duration: 108.698093ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:06:55.894509Z","caller":"traceutil/trace.go:171","msg":"trace[1125111138] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"121.790115ms","start":"2026-06-10T15:06:55.772685Z","end":"2026-06-10T15:06:55.894476Z","steps":["trace[1125111138] 'process raft request' (duration: 121.587431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:09.537975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.23522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T15:07:09.538064Z","caller":"traceutil/trace.go:171","msg":"trace[84036871] linearizableReadLoop","detail":"{readStateIndex:5636; appliedIndex:5635; }","duration":"134.974156ms","start":"2026-06-10T15:07:09.403078Z","end":"2026-06-10T15:07:09.538052Z","steps":["trace[84036871] 'read index received' (duration: 22.0055ms)","trace[84036871] 'applied index is now lower than readState.Index' (duration: 112.967596ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:09.538124Z","caller":"traceutil/trace.go:171","msg":"trace[1863132238] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"176.152999ms","start":"2026-06-10T15:07:09.361963Z","end":"2026-06-10T15:07:09.538116Z","steps":["trace[1863132238] 'process raft request' (duration: 63.158473ms)","trace[1863132238] 'compare' (duration: 112.102007ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:07:09.538488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.401345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-06-10T15:07:09.538532Z","caller":"traceutil/trace.go:171","msg":"trace[174514553] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5429; }","duration":"135.487467ms","start":"2026-06-10T15:07:09.403036Z","end":"2026-06-10T15:07:09.538523Z","steps":["trace[174514553] 'agreement among raft nodes before linearized reading' (duration: 135.341304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:09.538536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.032666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" ","response":"range_response_count:12 size:4407"} {"level":"info","ts":"2026-06-10T15:07:09.538622Z","caller":"traceutil/trace.go:171","msg":"trace[1619996182] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:5429; }","duration":"133.182159ms","start":"2026-06-10T15:07:09.405427Z","end":"2026-06-10T15:07:09.538609Z","steps":["trace[1619996182] 'agreement among raft nodes before linearized reading' (duration: 132.906864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:09.538853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.385333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" ","response":"range_response_count:43 size:296694"} {"level":"info","ts":"2026-06-10T15:07:09.538921Z","caller":"traceutil/trace.go:171","msg":"trace[531703385] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:43; response_revision:5429; }","duration":"132.454834ms","start":"2026-06-10T15:07:09.406451Z","end":"2026-06-10T15:07:09.538906Z","steps":["trace[531703385] 'agreement among raft nodes before linearized reading' (duration: 131.958174ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:10.109973Z","caller":"traceutil/trace.go:171","msg":"trace[1969940070] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"232.065806ms","start":"2026-06-10T15:07:09.877879Z","end":"2026-06-10T15:07:10.109945Z","steps":["trace[1969940070] 'process raft request' (duration: 227.156556ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:10.129638Z","caller":"traceutil/trace.go:171","msg":"trace[61952812] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"251.333851ms","start":"2026-06-10T15:07:09.878274Z","end":"2026-06-10T15:07:10.129608Z","steps":["trace[61952812] 'process raft request' (duration: 251.074956ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:10.129723Z","caller":"traceutil/trace.go:171","msg":"trace[589011616] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"251.014755ms","start":"2026-06-10T15:07:09.878627Z","end":"2026-06-10T15:07:10.129642Z","steps":["trace[589011616] 'process raft request' (duration: 250.863842ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:10.129878Z","caller":"traceutil/trace.go:171","msg":"trace[1043015374] linearizableReadLoop","detail":"{readStateIndex:5646; appliedIndex:5641; }","duration":"159.814445ms","start":"2026-06-10T15:07:09.970044Z","end":"2026-06-10T15:07:10.129859Z","steps":["trace[1043015374] 'read index received' (duration: 135.008527ms)","trace[1043015374] 'applied index is now lower than readState.Index' (duration: 24.804848ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:10.129997Z","caller":"traceutil/trace.go:171","msg":"trace[340228155] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"250.860552ms","start":"2026-06-10T15:07:09.879114Z","end":"2026-06-10T15:07:10.129974Z","steps":["trace[340228155] 'process raft request' (duration: 250.428873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:10.130034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.994128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wt8zk\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-10T15:07:10.13006Z","caller":"traceutil/trace.go:171","msg":"trace[544927309] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wt8zk; range_end:; response_count:1; response_revision:5439; }","duration":"160.138351ms","start":"2026-06-10T15:07:09.969914Z","end":"2026-06-10T15:07:10.130052Z","steps":["trace[544927309] 'agreement among raft nodes before linearized reading' (duration: 160.025699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:07:10.130245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.079472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-10T15:07:10.130395Z","caller":"traceutil/trace.go:171","msg":"trace[339981013] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5439; }","duration":"142.280186ms","start":"2026-06-10T15:07:09.988092Z","end":"2026-06-10T15:07:10.130373Z","steps":["trace[339981013] 'agreement among raft nodes before linearized reading' (duration: 141.954529ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:10.130431Z","caller":"traceutil/trace.go:171","msg":"trace[2020400876] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"240.532659ms","start":"2026-06-10T15:07:09.889873Z","end":"2026-06-10T15:07:10.130405Z","steps":["trace[2020400876] 'process raft request' (duration: 239.919747ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:23.493713Z","caller":"traceutil/trace.go:171","msg":"trace[593005064] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"105.855049ms","start":"2026-06-10T15:07:23.387824Z","end":"2026-06-10T15:07:23.493679Z","steps":["trace[593005064] 'process raft request' (duration: 92.973985ms)","trace[593005064] 'compare' (duration: 12.758741ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:23.498091Z","caller":"traceutil/trace.go:171","msg":"trace[2065978366] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"108.28474ms","start":"2026-06-10T15:07:23.389776Z","end":"2026-06-10T15:07:23.49806Z","steps":["trace[2065978366] 'process raft request' (duration: 108.145787ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:24.045227Z","caller":"traceutil/trace.go:171","msg":"trace[891618277] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"155.968096ms","start":"2026-06-10T15:07:23.889212Z","end":"2026-06-10T15:07:24.045181Z","steps":["trace[891618277] 'process raft request' (duration: 155.818373ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:07:24.045851Z","caller":"traceutil/trace.go:171","msg":"trace[95619977] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"157.525958ms","start":"2026-06-10T15:07:23.887964Z","end":"2026-06-10T15:07:24.045489Z","steps":["trace[95619977] 'process raft request' (duration: 113.977055ms)","trace[95619977] 'compare' (duration: 42.90328ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:24.180819Z","caller":"traceutil/trace.go:171","msg":"trace[450905633] transaction","detail":"{read_only:false; response_revision:5694; number_of_response:1; }","duration":"121.488631ms","start":"2026-06-10T15:07:24.059299Z","end":"2026-06-10T15:07:24.180787Z","steps":["trace[450905633] 'process raft request' (duration: 104.581294ms)","trace[450905633] 'compare' (duration: 16.778094ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:07:35.68496Z","caller":"traceutil/trace.go:171","msg":"trace[209377782] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"132.784171ms","start":"2026-06-10T15:07:35.552138Z","end":"2026-06-10T15:07:35.684922Z","steps":["trace[209377782] 'process raft request' (duration: 132.570807ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:08:02.591947Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-06-10T15:08:02.670149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"76.582411ms","hash":4162681396,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":33525760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T15:08:02.670216Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4162681396,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-06-10T15:12:52.727813Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020a6.wal"} {"level":"info","ts":"2026-06-10T15:13:02.599029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5964} {"level":"info","ts":"2026-06-10T15:13:02.698502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5964,"took":"97.566925ms","hash":4029448025,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":26820608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-10T15:13:02.698554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4029448025,"revision":5964,"compact-revision":3006} {"level":"info","ts":"2026-06-10T15:13:48.9027Z","caller":"traceutil/trace.go:171","msg":"trace[569753427] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"316.377848ms","start":"2026-06-10T15:13:48.586293Z","end":"2026-06-10T15:13:48.90267Z","steps":["trace[569753427] 'process raft request' (duration: 316.102933ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:48.902681Z","caller":"traceutil/trace.go:171","msg":"trace[900495538] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"235.484242ms","start":"2026-06-10T15:13:48.667139Z","end":"2026-06-10T15:13:48.902623Z","steps":["trace[900495538] 'read index received' (duration: 235.176746ms)","trace[900495538] 'applied index is now lower than readState.Index' (duration: 306.176µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:13:48.902881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.732867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:13:48.902974Z","caller":"traceutil/trace.go:171","msg":"trace[577888664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8549; }","duration":"235.835169ms","start":"2026-06-10T15:13:48.667117Z","end":"2026-06-10T15:13:48.902952Z","steps":["trace[577888664] 'agreement among raft nodes before linearized reading' (duration: 235.675946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:13:48.903174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:13:48.586273Z","time spent":"316.506401ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:13:48.959011Z","caller":"traceutil/trace.go:171","msg":"trace[1487696256] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"285.237743ms","start":"2026-06-10T15:13:48.673751Z","end":"2026-06-10T15:13:48.958989Z","steps":["trace[1487696256] 'process raft request' (duration: 284.961368ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:48.959125Z","caller":"traceutil/trace.go:171","msg":"trace[886470579] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"248.482217ms","start":"2026-06-10T15:13:48.710625Z","end":"2026-06-10T15:13:48.959107Z","steps":["trace[886470579] 'process raft request' (duration: 248.20581ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:48.959785Z","caller":"traceutil/trace.go:171","msg":"trace[1581587021] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"248.60146ms","start":"2026-06-10T15:13:48.711149Z","end":"2026-06-10T15:13:48.95975Z","steps":["trace[1581587021] 'process raft request' (duration: 247.777612ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:49.061306Z","caller":"traceutil/trace.go:171","msg":"trace[1836260984] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8860; }","duration":"101.139148ms","start":"2026-06-10T15:13:48.960141Z","end":"2026-06-10T15:13:49.06128Z","steps":["trace[1836260984] 'read index received' (duration: 91.492602ms)","trace[1836260984] 'applied index is now lower than readState.Index' (duration: 9.645566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:13:49.061469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.433979ms","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:5"} {"level":"info","ts":"2026-06-10T15:13:49.061521Z","caller":"traceutil/trace.go:171","msg":"trace[1950144389] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8554; }","duration":"115.549791ms","start":"2026-06-10T15:13:48.94596Z","end":"2026-06-10T15:13:49.061509Z","steps":["trace[1950144389] 'agreement among raft nodes before linearized reading' (duration: 115.409598ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:49.061434Z","caller":"traceutil/trace.go:171","msg":"trace[264588510] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"126.455673ms","start":"2026-06-10T15:13:48.934943Z","end":"2026-06-10T15:13:49.061399Z","steps":["trace[264588510] 'process raft request' (duration: 116.772187ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:13:51.171334Z","caller":"traceutil/trace.go:171","msg":"trace[558525367] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"104.757202ms","start":"2026-06-10T15:13:51.066557Z","end":"2026-06-10T15:13:51.171314Z","steps":["trace[558525367] 'process raft request' (duration: 104.63904ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:17.407704Z","caller":"traceutil/trace.go:171","msg":"trace[207417302] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9072; }","duration":"113.023942ms","start":"2026-06-10T15:14:17.294656Z","end":"2026-06-10T15:14:17.40768Z","steps":["trace[207417302] 'read index received' (duration: 112.798258ms)","trace[207417302] 'applied index is now lower than readState.Index' (duration: 224.584µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:14:17.407952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.283388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-10T15:14:17.40805Z","caller":"traceutil/trace.go:171","msg":"trace[1134395957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8758; }","duration":"113.449761ms","start":"2026-06-10T15:14:17.294588Z","end":"2026-06-10T15:14:17.408038Z","steps":["trace[1134395957] 'agreement among raft nodes before linearized reading' (duration: 113.200086ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:14:17.408393Z","caller":"traceutil/trace.go:171","msg":"trace[1758777394] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"141.011541ms","start":"2026-06-10T15:14:17.26737Z","end":"2026-06-10T15:14:17.408381Z","steps":["trace[1758777394] 'process raft request' (duration: 140.152594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:14:17.636335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.458857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-10T15:14:17.636524Z","caller":"traceutil/trace.go:171","msg":"trace[796245119] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8758; }","duration":"131.714893ms","start":"2026-06-10T15:14:17.504761Z","end":"2026-06-10T15:14:17.636476Z","steps":["trace[796245119] 'range keys from in-memory index tree' (duration: 130.742953ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:15:47.980025Z","caller":"traceutil/trace.go:171","msg":"trace[538513926] transaction","detail":"{read_only:false; response_revision:9616; number_of_response:1; }","duration":"145.038944ms","start":"2026-06-10T15:15:47.834956Z","end":"2026-06-10T15:15:47.979995Z","steps":["trace[538513926] 'process raft request' (duration: 144.706777ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:15:52.737518Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:15:52.744097Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T15:15:52.744241Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T15:18:02.605795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8134} {"level":"info","ts":"2026-06-10T15:18:02.667452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8134,"took":"59.078344ms","hash":3216434663,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":29601792,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-10T15:18:02.667515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3216434663,"revision":8134,"compact-revision":5964} {"level":"info","ts":"2026-06-10T15:19:46.910018Z","caller":"traceutil/trace.go:171","msg":"trace[515403816] transaction","detail":"{read_only:false; response_revision:11535; number_of_response:1; }","duration":"102.94665ms","start":"2026-06-10T15:19:46.807046Z","end":"2026-06-10T15:19:46.909993Z","steps":["trace[515403816] 'process raft request' (duration: 102.783257ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:23:02.615122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10559} {"level":"info","ts":"2026-06-10T15:23:02.695563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10559,"took":"78.000282ms","hash":2647897260,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":28487680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T15:23:02.695637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2647897260,"revision":10559,"compact-revision":8134} {"level":"info","ts":"2026-06-10T15:26:12.747045Z","caller":"traceutil/trace.go:171","msg":"trace[1567664516] transaction","detail":"{read_only:false; response_revision:14123; number_of_response:1; }","duration":"338.2795ms","start":"2026-06-10T15:26:12.408738Z","end":"2026-06-10T15:26:12.747018Z","steps":["trace[1567664516] 'process raft request' (duration: 338.019835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:26:12.747196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:26:12.408711Z","time spent":"338.403023ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:26:13.330801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.76434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-7cz47\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-06-10T15:26:13.330879Z","caller":"traceutil/trace.go:171","msg":"trace[2091978342] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-7cz47; range_end:; response_count:1; response_revision:14124; }","duration":"106.878773ms","start":"2026-06-10T15:26:13.223983Z","end":"2026-06-10T15:26:13.330862Z","steps":["trace[2091978342] 'range keys from in-memory index tree' (duration: 106.610897ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:26:22.604907Z","caller":"traceutil/trace.go:171","msg":"trace[1012948110] transaction","detail":"{read_only:false; response_revision:14291; number_of_response:1; }","duration":"132.451484ms","start":"2026-06-10T15:26:22.472419Z","end":"2026-06-10T15:26:22.60487Z","steps":["trace[1012948110] 'process raft request' (duration: 86.012697ms)","trace[1012948110] 'compare' (duration: 46.261853ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:26:22.914957Z","caller":"traceutil/trace.go:171","msg":"trace[908340027] linearizableReadLoop","detail":"{readStateIndex:14786; appliedIndex:14785; }","duration":"250.768033ms","start":"2026-06-10T15:26:22.664166Z","end":"2026-06-10T15:26:22.914927Z","steps":["trace[908340027] 'read index received' (duration: 250.548089ms)","trace[908340027] 'applied index is now lower than readState.Index' (duration: 211.474µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:26:22.914986Z","caller":"traceutil/trace.go:171","msg":"trace[1715127199] transaction","detail":"{read_only:false; response_revision:14292; number_of_response:1; }","duration":"293.929066ms","start":"2026-06-10T15:26:22.621028Z","end":"2026-06-10T15:26:22.914957Z","steps":["trace[1715127199] 'process raft request' (duration: 293.740132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:26:22.91509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.905876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T15:26:22.915133Z","caller":"traceutil/trace.go:171","msg":"trace[164743643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14292; }","duration":"250.993697ms","start":"2026-06-10T15:26:22.664126Z","end":"2026-06-10T15:26:22.91512Z","steps":["trace[164743643] 'agreement among raft nodes before linearized reading' (duration: 250.902915ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:02.270345Z","caller":"traceutil/trace.go:171","msg":"trace[536722886] linearizableReadLoop","detail":"{readStateIndex:15902; appliedIndex:15901; }","duration":"147.405991ms","start":"2026-06-10T15:28:02.122914Z","end":"2026-06-10T15:28:02.27032Z","steps":["trace[536722886] 'read index received' (duration: 147.35061ms)","trace[536722886] 'applied index is now lower than readState.Index' (duration: 54.561µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:28:02.270375Z","caller":"traceutil/trace.go:171","msg":"trace[526668516] transaction","detail":"{read_only:false; response_revision:15373; number_of_response:1; }","duration":"180.593319ms","start":"2026-06-10T15:28:02.089757Z","end":"2026-06-10T15:28:02.27035Z","steps":["trace[526668516] 'process raft request' (duration: 180.413426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:02.270608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.669806ms","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-06-10T15:28:02.270686Z","caller":"traceutil/trace.go:171","msg":"trace[2063704749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15373; }","duration":"147.780109ms","start":"2026-06-10T15:28:02.122887Z","end":"2026-06-10T15:28:02.270668Z","steps":["trace[2063704749] 'agreement among raft nodes before linearized reading' (duration: 147.532444ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:02.328123Z","caller":"traceutil/trace.go:171","msg":"trace[769442007] transaction","detail":"{read_only:false; response_revision:15375; number_of_response:1; }","duration":"162.394728ms","start":"2026-06-10T15:28:02.165704Z","end":"2026-06-10T15:28:02.328099Z","steps":["trace[769442007] 'process raft request' (duration: 162.345126ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:02.328535Z","caller":"traceutil/trace.go:171","msg":"trace[1065582405] transaction","detail":"{read_only:false; response_revision:15374; number_of_response:1; }","duration":"162.860806ms","start":"2026-06-10T15:28:02.165659Z","end":"2026-06-10T15:28:02.32852Z","steps":["trace[1065582405] 'process raft request' (duration: 162.261113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:02.328745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.2049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T15:28:02.328827Z","caller":"traceutil/trace.go:171","msg":"trace[1371772097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15375; }","duration":"202.350253ms","start":"2026-06-10T15:28:02.126456Z","end":"2026-06-10T15:28:02.328806Z","steps":["trace[1371772097] 'agreement among raft nodes before linearized reading' (duration: 202.104148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:28:02.329194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.598918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-10T15:28:02.32926Z","caller":"traceutil/trace.go:171","msg":"trace[19557309] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:15375; }","duration":"177.68139ms","start":"2026-06-10T15:28:02.151558Z","end":"2026-06-10T15:28:02.32924Z","steps":["trace[19557309] 'agreement among raft nodes before linearized reading' (duration: 177.490006ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:28:02.656114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12802} {"level":"info","ts":"2026-06-10T15:28:03.043752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12802,"took":"384.025815ms","hash":1136179102,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":32206848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T15:28:03.043803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1136179102,"revision":12802,"compact-revision":10559} {"level":"info","ts":"2026-06-10T15:33:02.665205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15380} {"level":"info","ts":"2026-06-10T15:33:02.76115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15380,"took":"93.302974ms","hash":3703829584,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":33345536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-10T15:33:02.761202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703829584,"revision":15380,"compact-revision":12802} {"level":"info","ts":"2026-06-10T15:33:45.657308Z","caller":"traceutil/trace.go:171","msg":"trace[1029169807] transaction","detail":"{read_only:false; response_revision:17995; number_of_response:1; }","duration":"187.267572ms","start":"2026-06-10T15:33:45.470024Z","end":"2026-06-10T15:33:45.657292Z","steps":["trace[1029169807] 'process raft request' (duration: 187.17491ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:45.687988Z","caller":"traceutil/trace.go:171","msg":"trace[1876573588] transaction","detail":"{read_only:false; response_revision:17996; number_of_response:1; }","duration":"217.869386ms","start":"2026-06-10T15:33:45.470087Z","end":"2026-06-10T15:33:45.687957Z","steps":["trace[1876573588] 'process raft request' (duration: 217.713743ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:45.688011Z","caller":"traceutil/trace.go:171","msg":"trace[1373058080] transaction","detail":"{read_only:false; response_revision:17997; number_of_response:1; }","duration":"212.718723ms","start":"2026-06-10T15:33:45.475261Z","end":"2026-06-10T15:33:45.68798Z","steps":["trace[1373058080] 'process raft request' (duration: 212.617411ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:46.337065Z","caller":"traceutil/trace.go:171","msg":"trace[1684874296] linearizableReadLoop","detail":"{readStateIndex:18615; appliedIndex:18614; }","duration":"221.02027ms","start":"2026-06-10T15:33:46.116015Z","end":"2026-06-10T15:33:46.337035Z","steps":["trace[1684874296] 'read index received' (duration: 220.789975ms)","trace[1684874296] 'applied index is now lower than readState.Index' (duration: 229.385µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:33:46.337411Z","caller":"traceutil/trace.go:171","msg":"trace[1220253032] transaction","detail":"{read_only:false; response_revision:18000; number_of_response:1; }","duration":"239.603552ms","start":"2026-06-10T15:33:46.097795Z","end":"2026-06-10T15:33:46.337399Z","steps":["trace[1220253032] 'process raft request' (duration: 239.100652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.337712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.681692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-j7jh7\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-06-10T15:33:46.337753Z","caller":"traceutil/trace.go:171","msg":"trace[961544756] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-j7jh7; range_end:; response_count:1; response_revision:18000; }","duration":"221.772084ms","start":"2026-06-10T15:33:46.11597Z","end":"2026-06-10T15:33:46.337742Z","steps":["trace[961544756] 'agreement among raft nodes before linearized reading' (duration: 221.631742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.337964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.776181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-10T15:33:46.338012Z","caller":"traceutil/trace.go:171","msg":"trace[954189134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18000; }","duration":"177.846952ms","start":"2026-06-10T15:33:46.160157Z","end":"2026-06-10T15:33:46.338004Z","steps":["trace[954189134] 'agreement among raft nodes before linearized reading' (duration: 177.74685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.338377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.020756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T15:33:46.338413Z","caller":"traceutil/trace.go:171","msg":"trace[2131851883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18000; }","duration":"174.057347ms","start":"2026-06-10T15:33:46.164348Z","end":"2026-06-10T15:33:46.338405Z","steps":["trace[2131851883] 'agreement among raft nodes before linearized reading' (duration: 173.971445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.338583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.398333ms","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-06-10T15:33:46.338616Z","caller":"traceutil/trace.go:171","msg":"trace[6520327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18000; }","duration":"177.455343ms","start":"2026-06-10T15:33:46.161153Z","end":"2026-06-10T15:33:46.338608Z","steps":["trace[6520327] 'agreement among raft nodes before linearized reading' (duration: 177.377202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.33878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.411954ms","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-06-10T15:33:46.338815Z","caller":"traceutil/trace.go:171","msg":"trace[1348428182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18000; }","duration":"178.461384ms","start":"2026-06-10T15:33:46.160343Z","end":"2026-06-10T15:33:46.338804Z","steps":["trace[1348428182] 'agreement among raft nodes before linearized reading' (duration: 178.379303ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:46.577421Z","caller":"traceutil/trace.go:171","msg":"trace[696362470] transaction","detail":"{read_only:false; response_revision:18001; number_of_response:1; }","duration":"235.102642ms","start":"2026-06-10T15:33:46.342295Z","end":"2026-06-10T15:33:46.577397Z","steps":["trace[696362470] 'process raft request' (duration: 234.959139ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:46.597923Z","caller":"traceutil/trace.go:171","msg":"trace[1568765953] linearizableReadLoop","detail":"{readStateIndex:18619; appliedIndex:18615; }","duration":"254.337729ms","start":"2026-06-10T15:33:46.343565Z","end":"2026-06-10T15:33:46.597903Z","steps":["trace[1568765953] 'read index received' (duration: 233.691604ms)","trace[1568765953] 'applied index is now lower than readState.Index' (duration: 20.645215ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:33:46.59818Z","caller":"traceutil/trace.go:171","msg":"trace[1752139997] transaction","detail":"{read_only:false; response_revision:18002; number_of_response:1; }","duration":"255.740716ms","start":"2026-06-10T15:33:46.342428Z","end":"2026-06-10T15:33:46.598169Z","steps":["trace[1752139997] 'process raft request' (duration: 255.275597ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:46.598355Z","caller":"traceutil/trace.go:171","msg":"trace[1349581497] transaction","detail":"{read_only:false; response_revision:18003; number_of_response:1; }","duration":"255.577123ms","start":"2026-06-10T15:33:46.342769Z","end":"2026-06-10T15:33:46.598346Z","steps":["trace[1349581497] 'process raft request' (duration: 255.058452ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:33:46.598475Z","caller":"traceutil/trace.go:171","msg":"trace[83823730] transaction","detail":"{read_only:false; response_revision:18004; number_of_response:1; }","duration":"255.622074ms","start":"2026-06-10T15:33:46.342844Z","end":"2026-06-10T15:33:46.598466Z","steps":["trace[83823730] 'process raft request' (duration: 255.023732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.599015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.993841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19044"} {"level":"info","ts":"2026-06-10T15:33:46.59907Z","caller":"traceutil/trace.go:171","msg":"trace[1299566131] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:18004; }","duration":"132.106363ms","start":"2026-06-10T15:33:46.466951Z","end":"2026-06-10T15:33:46.599057Z","steps":["trace[1299566131] 'agreement among raft nodes before linearized reading' (duration: 131.98259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:33:46.599773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.171256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:946418"} {"level":"info","ts":"2026-06-10T15:33:46.599864Z","caller":"traceutil/trace.go:171","msg":"trace[1942953328] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18004; }","duration":"256.295628ms","start":"2026-06-10T15:33:46.34355Z","end":"2026-06-10T15:33:46.599846Z","steps":["trace[1942953328] 'agreement among raft nodes before linearized reading' (duration: 255.014302ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:36:25.45015Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:36:25.45552Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-10T15:36:25.455648Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-10T15:38:02.674806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17591} {"level":"info","ts":"2026-06-10T15:38:02.77094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17591,"took":"93.02026ms","hash":947993135,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":35311616,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-10T15:38:02.77101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":947993135,"revision":17591,"compact-revision":15380} {"level":"warn","ts":"2026-06-10T15:39:05.956927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.617322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:39:05.957055Z","caller":"traceutil/trace.go:171","msg":"trace[736906881] linearizableReadLoop","detail":"{readStateIndex:21141; appliedIndex:21140; }","duration":"101.07375ms","start":"2026-06-10T15:39:05.855962Z","end":"2026-06-10T15:39:05.957036Z","steps":["trace[736906881] 'read index received' (duration: 36.57µs)","trace[736906881] 'applied index is now lower than readState.Index' (duration: 101.03592ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:39:05.957145Z","caller":"traceutil/trace.go:171","msg":"trace[831353261] transaction","detail":"{read_only:false; response_revision:20443; number_of_response:1; }","duration":"188.392766ms","start":"2026-06-10T15:39:05.768739Z","end":"2026-06-10T15:39:05.957132Z","steps":["trace[831353261] 'process raft request' (duration: 23.453868ms)","trace[831353261] 'compare' (duration: 164.474609ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:39:05.957444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.465368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:39:05.957517Z","caller":"traceutil/trace.go:171","msg":"trace[464493039] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:20443; }","duration":"101.578071ms","start":"2026-06-10T15:39:05.85592Z","end":"2026-06-10T15:39:05.957498Z","steps":["trace[464493039] 'agreement among raft nodes before linearized reading' (duration: 101.466288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:39:06.227336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.063061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:39:06.227796Z","caller":"traceutil/trace.go:171","msg":"trace[1181906369] linearizableReadLoop","detail":"{readStateIndex:21142; appliedIndex:21141; }","duration":"210.765574ms","start":"2026-06-10T15:39:06.017009Z","end":"2026-06-10T15:39:06.227775Z","steps":["trace[1181906369] 'read index received' (duration: 52.110071ms)","trace[1181906369] 'applied index is now lower than readState.Index' (duration: 158.653853ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:39:06.2278Z","caller":"traceutil/trace.go:171","msg":"trace[668918594] transaction","detail":"{read_only:false; response_revision:20444; number_of_response:1; }","duration":"234.271604ms","start":"2026-06-10T15:39:05.993462Z","end":"2026-06-10T15:39:06.227734Z","steps":["trace[668918594] 'process raft request' (duration: 75.718003ms)","trace[668918594] 'compare' (duration: 157.931019ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:39:06.227904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.907606ms","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-06-10T15:39:06.227935Z","caller":"traceutil/trace.go:171","msg":"trace[1300394302] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20444; }","duration":"210.995478ms","start":"2026-06-10T15:39:06.016929Z","end":"2026-06-10T15:39:06.227925Z","steps":["trace[1300394302] 'agreement among raft nodes before linearized reading' (duration: 210.933836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:39:06.227999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.139822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-10T15:39:06.228073Z","caller":"traceutil/trace.go:171","msg":"trace[174277868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20444; }","duration":"151.257804ms","start":"2026-06-10T15:39:06.076797Z","end":"2026-06-10T15:39:06.228055Z","steps":["trace[174277868] 'agreement among raft nodes before linearized reading' (duration: 151.090001ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:39:06.543525Z","caller":"traceutil/trace.go:171","msg":"trace[5990962] transaction","detail":"{read_only:false; response_revision:20445; number_of_response:1; }","duration":"311.596841ms","start":"2026-06-10T15:39:06.231901Z","end":"2026-06-10T15:39:06.543497Z","steps":["trace[5990962] 'process raft request' (duration: 233.912368ms)","trace[5990962] 'compare' (duration: 77.475539ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:39:06.543676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:39:06.231888Z","time spent":"311.708783ms","remote":"127.0.0.1:33550","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-06-10T15:39:06.544852Z","caller":"traceutil/trace.go:171","msg":"trace[1966799274] transaction","detail":"{read_only:false; response_revision:20447; number_of_response:1; }","duration":"312.522248ms","start":"2026-06-10T15:39:06.232316Z","end":"2026-06-10T15:39:06.544838Z","steps":["trace[1966799274] 'process raft request' (duration: 312.449277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:39:06.544938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:39:06.232312Z","time spent":"312.583159ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:39:06.544936Z","caller":"traceutil/trace.go:171","msg":"trace[1297925074] transaction","detail":"{read_only:false; response_revision:20446; number_of_response:1; }","duration":"312.597289ms","start":"2026-06-10T15:39:06.232277Z","end":"2026-06-10T15:39:06.544874Z","steps":["trace[1297925074] 'process raft request' (duration: 312.388255ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:39:06.545076Z","caller":"traceutil/trace.go:171","msg":"trace[1271431517] linearizableReadLoop","detail":"{readStateIndex:21146; appliedIndex:21142; }","duration":"310.472298ms","start":"2026-06-10T15:39:06.234553Z","end":"2026-06-10T15:39:06.545025Z","steps":["trace[1271431517] 'read index received' (duration: 232.127222ms)","trace[1271431517] 'applied index is now lower than readState.Index' (duration: 78.341446ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:39:06.545121Z","caller":"traceutil/trace.go:171","msg":"trace[696983792] transaction","detail":"{read_only:false; response_revision:20448; number_of_response:1; }","duration":"312.458207ms","start":"2026-06-10T15:39:06.23261Z","end":"2026-06-10T15:39:06.545069Z","steps":["trace[696983792] 'process raft request' (duration: 312.195101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:39:06.545199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:39:06.232268Z","time spent":"312.783132ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:39:06.545324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.738213ms","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-06-10T15:39:06.545415Z","caller":"traceutil/trace.go:171","msg":"trace[1257191759] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20448; }","duration":"310.844355ms","start":"2026-06-10T15:39:06.234551Z","end":"2026-06-10T15:39:06.545395Z","steps":["trace[1257191759] 'agreement among raft nodes before linearized reading' (duration: 310.555959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:39:06.545352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:39:06.232574Z","time spent":"312.6619ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:39:06.545467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:39:06.23453Z","time spent":"310.928036ms","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-10T15:39:06.869848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.780784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:39:06.869948Z","caller":"traceutil/trace.go:171","msg":"trace[1742773363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20449; }","duration":"204.937807ms","start":"2026-06-10T15:39:06.664987Z","end":"2026-06-10T15:39:06.869925Z","steps":["trace[1742773363] 'range keys from in-memory index tree' (duration: 204.693082ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:39:07.089881Z","caller":"traceutil/trace.go:171","msg":"trace[1293054379] linearizableReadLoop","detail":"{readStateIndex:21150; appliedIndex:21148; }","duration":"218.356586ms","start":"2026-06-10T15:39:06.871489Z","end":"2026-06-10T15:39:07.089846Z","steps":["trace[1293054379] 'read index received' (duration: 217.753433ms)","trace[1293054379] 'applied index is now lower than readState.Index' (duration: 601.903µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:39:07.090083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.57969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:39:07.090122Z","caller":"traceutil/trace.go:171","msg":"trace[1008335430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20452; }","duration":"218.684982ms","start":"2026-06-10T15:39:06.871424Z","end":"2026-06-10T15:39:07.090109Z","steps":["trace[1008335430] 'agreement among raft nodes before linearized reading' (duration: 218.562139ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:39:07.090484Z","caller":"traceutil/trace.go:171","msg":"trace[1179352523] transaction","detail":"{read_only:false; response_revision:20451; number_of_response:1; }","duration":"290.421017ms","start":"2026-06-10T15:39:06.800051Z","end":"2026-06-10T15:39:07.090472Z","steps":["trace[1179352523] 'process raft request' (duration: 289.302344ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:39:07.090727Z","caller":"traceutil/trace.go:171","msg":"trace[1337472328] transaction","detail":"{read_only:false; response_revision:20452; number_of_response:1; }","duration":"287.986467ms","start":"2026-06-10T15:39:06.802718Z","end":"2026-06-10T15:39:07.090704Z","steps":["trace[1337472328] 'process raft request' (duration: 287.07539ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:43:02.810088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20119} {"level":"warn","ts":"2026-06-10T15:43:02.817075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.883249ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-06-10T15:43:02.8172Z","caller":"traceutil/trace.go:171","msg":"trace[2016392100] linearizableReadLoop","detail":"{readStateIndex:23654; appliedIndex:23653; }","duration":"126.188295ms","start":"2026-06-10T15:43:02.690993Z","end":"2026-06-10T15:43:02.817182Z","steps":["trace[2016392100] 'read index received' (duration: 87.962µs)","trace[2016392100] 'applied index is now lower than readState.Index' (duration: 126.098863ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:43:02.845943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.932462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:43:02.845957Z","caller":"traceutil/trace.go:171","msg":"trace[76284082] compact","detail":"{revision:20119; response_revision:22878; }","duration":"155.456542ms","start":"2026-06-10T15:43:02.69046Z","end":"2026-06-10T15:43:02.845916Z","steps":["trace[76284082] 'check and update compact revision' (duration: 118.604482ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:43:02.846047Z","caller":"traceutil/trace.go:171","msg":"trace[1222786326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22878; }","duration":"155.082535ms","start":"2026-06-10T15:43:02.690942Z","end":"2026-06-10T15:43:02.846025Z","steps":["trace[1222786326] 'agreement among raft nodes before linearized reading' (duration: 126.291597ms)","trace[1222786326] 'range keys from in-memory index tree' (duration: 28.641215ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:43:02.959413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20119,"took":"145.611504ms","hash":787277544,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":41476096,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-10T15:43:02.959482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":787277544,"revision":20119,"compact-revision":17591} {"level":"info","ts":"2026-06-10T15:46:31.158556Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000064a6.wal"} {"level":"warn","ts":"2026-06-10T15:46:42.768367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.822125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:42.768451Z","caller":"traceutil/trace.go:171","msg":"trace[2087849435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25077; }","duration":"100.955518ms","start":"2026-06-10T15:46:42.667477Z","end":"2026-06-10T15:46:42.768432Z","steps":["trace[2087849435] 'range keys from in-memory index tree' (duration: 100.688573ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:42.911683Z","caller":"traceutil/trace.go:171","msg":"trace[1541586954] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"129.104433ms","start":"2026-06-10T15:46:42.78256Z","end":"2026-06-10T15:46:42.911664Z","steps":["trace[1541586954] 'process raft request' (duration: 128.9573ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:43.180893Z","caller":"traceutil/trace.go:171","msg":"trace[878653699] linearizableReadLoop","detail":"{readStateIndex:25914; appliedIndex:25911; }","duration":"315.928955ms","start":"2026-06-10T15:46:42.864949Z","end":"2026-06-10T15:46:43.180878Z","steps":["trace[878653699] 'read index received' (duration: 46.652767ms)","trace[878653699] 'applied index is now lower than readState.Index' (duration: 269.275258ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:43.181212Z","caller":"traceutil/trace.go:171","msg":"trace[1162053381] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"381.535942ms","start":"2026-06-10T15:46:42.799651Z","end":"2026-06-10T15:46:43.181187Z","steps":["trace[1162053381] 'process raft request' (duration: 381.193306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.343493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-10T15:46:43.181333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:42.799634Z","time spent":"381.631164ms","remote":"127.0.0.1:33550","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-06-10T15:46:43.18134Z","caller":"traceutil/trace.go:171","msg":"trace[1490868653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25080; }","duration":"316.413945ms","start":"2026-06-10T15:46:42.864919Z","end":"2026-06-10T15:46:43.181333Z","steps":["trace[1490868653] 'agreement among raft nodes before linearized reading' (duration: 316.281963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:42.864903Z","time spent":"316.458896ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T15:46:43.181381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.583338ms","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-06-10T15:46:43.181425Z","caller":"traceutil/trace.go:171","msg":"trace[2093087999] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"396.554714ms","start":"2026-06-10T15:46:42.784862Z","end":"2026-06-10T15:46:43.181417Z","steps":["trace[2093087999] 'process raft request' (duration: 300.650148ms)","trace[2093087999] 'compare' (duration: 95.154591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:43.181456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:42.784845Z","time spent":"396.594694ms","remote":"127.0.0.1:33550","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-06-10T15:46:43.181455Z","caller":"traceutil/trace.go:171","msg":"trace[29208320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25080; }","duration":"308.689229ms","start":"2026-06-10T15:46:42.872749Z","end":"2026-06-10T15:46:43.181439Z","steps":["trace[29208320] 'agreement among raft nodes before linearized reading' (duration: 308.466935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.204595ms","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-06-10T15:46:43.181503Z","caller":"traceutil/trace.go:171","msg":"trace[1227523026] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25080; }","duration":"144.254926ms","start":"2026-06-10T15:46:43.037242Z","end":"2026-06-10T15:46:43.181497Z","steps":["trace[1227523026] 'agreement among raft nodes before linearized reading' (duration: 144.226466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.84929ms","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-06-10T15:46:43.18156Z","caller":"traceutil/trace.go:171","msg":"trace[208724978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25080; }","duration":"275.935681ms","start":"2026-06-10T15:46:42.9056Z","end":"2026-06-10T15:46:43.181536Z","steps":["trace[208724978] 'agreement among raft nodes before linearized reading' (duration: 275.827889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.003342ms","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-06-10T15:46:43.181635Z","caller":"traceutil/trace.go:171","msg":"trace[900408343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25080; }","duration":"276.041193ms","start":"2026-06-10T15:46:42.90558Z","end":"2026-06-10T15:46:43.181621Z","steps":["trace[900408343] 'agreement among raft nodes before linearized reading' (duration: 275.943441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:43.181501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:42.872739Z","time spent":"308.75401ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T15:46:43.181774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.39166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T15:46:43.181801Z","caller":"traceutil/trace.go:171","msg":"trace[2092616016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25080; }","duration":"276.443431ms","start":"2026-06-10T15:46:42.905351Z","end":"2026-06-10T15:46:43.181795Z","steps":["trace[2092616016] 'agreement among raft nodes before linearized reading' (duration: 276.3805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:43.37083Z","caller":"traceutil/trace.go:171","msg":"trace[934272083] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"184.357343ms","start":"2026-06-10T15:46:43.186449Z","end":"2026-06-10T15:46:43.370806Z","steps":["trace[934272083] 'process raft request' (duration: 184.311502ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:43.371024Z","caller":"traceutil/trace.go:171","msg":"trace[1765455877] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"184.832112ms","start":"2026-06-10T15:46:43.18616Z","end":"2026-06-10T15:46:43.370992Z","steps":["trace[1765455877] 'process raft request' (duration: 149.631194ms)","trace[1765455877] 'compare' (duration: 34.623196ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:43.371159Z","caller":"traceutil/trace.go:171","msg":"trace[1024131667] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"184.864163ms","start":"2026-06-10T15:46:43.186263Z","end":"2026-06-10T15:46:43.371127Z","steps":["trace[1024131667] 'process raft request' (duration: 184.338892ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:43.371186Z","caller":"traceutil/trace.go:171","msg":"trace[759741553] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"184.848582ms","start":"2026-06-10T15:46:43.186315Z","end":"2026-06-10T15:46:43.371163Z","steps":["trace[759741553] 'process raft request' (duration: 184.386513ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:43.372575Z","caller":"traceutil/trace.go:171","msg":"trace[405832854] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"165.653417ms","start":"2026-06-10T15:46:43.206908Z","end":"2026-06-10T15:46:43.372562Z","steps":["trace[405832854] 'process raft request' (duration: 165.339491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.352775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.853038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:47.352893Z","caller":"traceutil/trace.go:171","msg":"trace[803520173] linearizableReadLoop","detail":"{readStateIndex:25952; appliedIndex:25951; }","duration":"141.055483ms","start":"2026-06-10T15:46:47.211817Z","end":"2026-06-10T15:46:47.352872Z","steps":["trace[803520173] 'read index received' (duration: 14.91952ms)","trace[803520173] 'applied index is now lower than readState.Index' (duration: 126.134693ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:47.353001Z","caller":"traceutil/trace.go:171","msg":"trace[503946370] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"151.636376ms","start":"2026-06-10T15:46:47.201332Z","end":"2026-06-10T15:46:47.352968Z","steps":["trace[503946370] 'process raft request' (duration: 25.484052ms)","trace[503946370] 'compare' (duration: 125.687435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:47.353357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.534312ms","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-06-10T15:46:47.353387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.912757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-06-10T15:46:47.353454Z","caller":"traceutil/trace.go:171","msg":"trace[35938995] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25117; }","duration":"102.033279ms","start":"2026-06-10T15:46:47.251404Z","end":"2026-06-10T15:46:47.353437Z","steps":["trace[35938995] 'agreement among raft nodes before linearized reading' (duration: 101.821515ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:47.353425Z","caller":"traceutil/trace.go:171","msg":"trace[185470961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25117; }","duration":"141.634095ms","start":"2026-06-10T15:46:47.211774Z","end":"2026-06-10T15:46:47.353408Z","steps":["trace[185470961] 'agreement among raft nodes before linearized reading' (duration: 141.199646ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:47.61676Z","caller":"traceutil/trace.go:171","msg":"trace[1986850409] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25952; }","duration":"258.028652ms","start":"2026-06-10T15:46:47.358701Z","end":"2026-06-10T15:46:47.61673Z","steps":["trace[1986850409] 'read index received' (duration: 220.678432ms)","trace[1986850409] 'applied index is now lower than readState.Index' (duration: 37.34916ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:47.616926Z","caller":"traceutil/trace.go:171","msg":"trace[482148415] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"260.088643ms","start":"2026-06-10T15:46:47.356797Z","end":"2026-06-10T15:46:47.616886Z","steps":["trace[482148415] 'process raft request' (duration: 222.56845ms)","trace[482148415] 'compare' (duration: 37.257988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:47.617028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.315827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-06-10T15:46:47.617067Z","caller":"traceutil/trace.go:171","msg":"trace[1445429064] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25118; }","duration":"258.473681ms","start":"2026-06-10T15:46:47.358582Z","end":"2026-06-10T15:46:47.617055Z","steps":["trace[1445429064] 'agreement among raft nodes before linearized reading' (duration: 258.298467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.625008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.540381ms","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-06-10T15:46:47.625022Z","caller":"traceutil/trace.go:171","msg":"trace[1392714315] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"240.248704ms","start":"2026-06-10T15:46:47.384737Z","end":"2026-06-10T15:46:47.624986Z","steps":["trace[1392714315] 'process raft request' (duration: 239.947708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.625034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.243585ms","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-06-10T15:46:47.625108Z","caller":"traceutil/trace.go:171","msg":"trace[1047483843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25119; }","duration":"238.349707ms","start":"2026-06-10T15:46:47.38674Z","end":"2026-06-10T15:46:47.625089Z","steps":["trace[1047483843] 'agreement among raft nodes before linearized reading' (duration: 238.136683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.625196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.764945ms","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-06-10T15:46:47.625347Z","caller":"traceutil/trace.go:171","msg":"trace[337329285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25119; }","duration":"239.921138ms","start":"2026-06-10T15:46:47.385382Z","end":"2026-06-10T15:46:47.625303Z","steps":["trace[337329285] 'agreement among raft nodes before linearized reading' (duration: 239.436539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.625097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.9859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T15:46:47.625056Z","caller":"traceutil/trace.go:171","msg":"trace[367376464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25119; }","duration":"238.648513ms","start":"2026-06-10T15:46:47.386396Z","end":"2026-06-10T15:46:47.625044Z","steps":["trace[367376464] 'agreement among raft nodes before linearized reading' (duration: 238.49708ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:47.625449Z","caller":"traceutil/trace.go:171","msg":"trace[1940527340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25119; }","duration":"239.315086ms","start":"2026-06-10T15:46:47.386096Z","end":"2026-06-10T15:46:47.625411Z","steps":["trace[1940527340] 'agreement among raft nodes before linearized reading' (duration: 238.884838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:47.896377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.2476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:47.896523Z","caller":"traceutil/trace.go:171","msg":"trace[1183194182] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"265.973722ms","start":"2026-06-10T15:46:47.630531Z","end":"2026-06-10T15:46:47.896505Z","steps":["trace[1183194182] 'process raft request' (duration: 89.518668ms)","trace[1183194182] 'compare' (duration: 176.120118ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:47.896945Z","caller":"traceutil/trace.go:171","msg":"trace[1769069040] linearizableReadLoop","detail":"{readStateIndex:25958; appliedIndex:25955; }","duration":"229.587452ms","start":"2026-06-10T15:46:47.66734Z","end":"2026-06-10T15:46:47.896927Z","steps":["trace[1769069040] 'read index received' (duration: 52.680539ms)","trace[1769069040] 'applied index is now lower than readState.Index' (duration: 176.906113ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:47.897035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.687873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:47.897036Z","caller":"traceutil/trace.go:171","msg":"trace[1733929443] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"266.40408ms","start":"2026-06-10T15:46:47.630619Z","end":"2026-06-10T15:46:47.897023Z","steps":["trace[1733929443] 'process raft request' (duration: 266.222837ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:47.897061Z","caller":"traceutil/trace.go:171","msg":"trace[551962320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25123; }","duration":"229.757514ms","start":"2026-06-10T15:46:47.667296Z","end":"2026-06-10T15:46:47.897053Z","steps":["trace[551962320] 'agreement among raft nodes before linearized reading' (duration: 229.699183ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:47.897091Z","caller":"traceutil/trace.go:171","msg":"trace[604785280] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"266.482862ms","start":"2026-06-10T15:46:47.630579Z","end":"2026-06-10T15:46:47.897061Z","steps":["trace[604785280] 'process raft request' (duration: 265.889511ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:48.329729Z","caller":"traceutil/trace.go:171","msg":"trace[1860524038] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"166.130856ms","start":"2026-06-10T15:46:48.163577Z","end":"2026-06-10T15:46:48.329708Z","steps":["trace[1860524038] 'process raft request' (duration: 165.971293ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:48.647604Z","caller":"traceutil/trace.go:171","msg":"trace[1402011373] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"121.967569ms","start":"2026-06-10T15:46:48.525611Z","end":"2026-06-10T15:46:48.647578Z","steps":["trace[1402011373] 'process raft request' (duration: 120.702634ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:49.481314Z","caller":"traceutil/trace.go:171","msg":"trace[19373569] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"271.082995ms","start":"2026-06-10T15:46:49.210209Z","end":"2026-06-10T15:46:49.481292Z","steps":["trace[19373569] 'process raft request' (duration: 270.938152ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:49.4814Z","caller":"traceutil/trace.go:171","msg":"trace[364679455] linearizableReadLoop","detail":"{readStateIndex:25964; appliedIndex:25964; }","duration":"269.304618ms","start":"2026-06-10T15:46:49.212072Z","end":"2026-06-10T15:46:49.481376Z","steps":["trace[364679455] 'read index received' (duration: 269.289748ms)","trace[364679455] 'applied index is now lower than readState.Index' (duration: 11.62µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:49.481556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.457382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:49.481597Z","caller":"traceutil/trace.go:171","msg":"trace[1627014915] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25129; }","duration":"269.549003ms","start":"2026-06-10T15:46:49.212035Z","end":"2026-06-10T15:46:49.481584Z","steps":["trace[1627014915] 'agreement among raft nodes before linearized reading' (duration: 269.450041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:49.541831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.707102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:49.541906Z","caller":"traceutil/trace.go:171","msg":"trace[1291692084] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25130; }","duration":"328.848075ms","start":"2026-06-10T15:46:49.213042Z","end":"2026-06-10T15:46:49.54189Z","steps":["trace[1291692084] 'agreement among raft nodes before linearized reading' (duration: 328.710142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:49.541945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:49.213011Z","time spent":"328.924167ms","remote":"127.0.0.1:48472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-06-10T15:46:49.541989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.737784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-10T15:46:49.54203Z","caller":"traceutil/trace.go:171","msg":"trace[1982374369] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25130; }","duration":"101.812075ms","start":"2026-06-10T15:46:49.440206Z","end":"2026-06-10T15:46:49.542018Z","steps":["trace[1982374369] 'agreement among raft nodes before linearized reading' (duration: 101.674673ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:49.541842Z","caller":"traceutil/trace.go:171","msg":"trace[1369317999] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"182.887214ms","start":"2026-06-10T15:46:49.358934Z","end":"2026-06-10T15:46:49.541821Z","steps":["trace[1369317999] 'process raft request' (duration: 182.68588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:49.542145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.572094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:49.542224Z","caller":"traceutil/trace.go:171","msg":"trace[1371385567] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25130; }","duration":"151.711467ms","start":"2026-06-10T15:46:49.390488Z","end":"2026-06-10T15:46:49.542199Z","steps":["trace[1371385567] 'agreement among raft nodes before linearized reading' (duration: 151.547194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:49.6914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.88025ms","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-06-10T15:46:49.691491Z","caller":"traceutil/trace.go:171","msg":"trace[161084682] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25130; }","duration":"143.016233ms","start":"2026-06-10T15:46:49.548452Z","end":"2026-06-10T15:46:49.691468Z","steps":["trace[161084682] 'range keys from in-memory index tree' (duration: 142.791777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:49.691426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.88672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:49.691576Z","caller":"traceutil/trace.go:171","msg":"trace[186302650] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25130; }","duration":"146.081543ms","start":"2026-06-10T15:46:49.545475Z","end":"2026-06-10T15:46:49.691557Z","steps":["trace[186302650] 'count revisions from in-memory index tree' (duration: 145.824839ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:49.867497Z","caller":"traceutil/trace.go:171","msg":"trace[1583084669] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25966; }","duration":"168.459094ms","start":"2026-06-10T15:46:49.699Z","end":"2026-06-10T15:46:49.867459Z","steps":["trace[1583084669] 'read index received' (duration: 16.497552ms)","trace[1583084669] 'applied index is now lower than readState.Index' (duration: 151.959841ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:49.86754Z","caller":"traceutil/trace.go:171","msg":"trace[225479234] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"171.957784ms","start":"2026-06-10T15:46:49.695517Z","end":"2026-06-10T15:46:49.867474Z","steps":["trace[225479234] 'process raft request' (duration: 153.231367ms)","trace[225479234] 'compare' (duration: 18.565424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:49.867678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.655447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:46:49.867751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.660854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:46:49.867767Z","caller":"traceutil/trace.go:171","msg":"trace[1405593896] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25133; }","duration":"168.756219ms","start":"2026-06-10T15:46:49.69899Z","end":"2026-06-10T15:46:49.867746Z","steps":["trace[1405593896] 'agreement among raft nodes before linearized reading' (duration: 168.613336ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:49.867816Z","caller":"traceutil/trace.go:171","msg":"trace[1747402344] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25133; }","duration":"113.790276ms","start":"2026-06-10T15:46:49.754007Z","end":"2026-06-10T15:46:49.867797Z","steps":["trace[1747402344] 'agreement among raft nodes before linearized reading' (duration: 113.653384ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:50.145834Z","caller":"traceutil/trace.go:171","msg":"trace[3261556] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"241.634563ms","start":"2026-06-10T15:46:49.904127Z","end":"2026-06-10T15:46:50.145762Z","steps":["trace[3261556] 'process raft request' (duration: 236.00479ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:50.238021Z","caller":"traceutil/trace.go:171","msg":"trace[655761061] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"332.679902ms","start":"2026-06-10T15:46:49.905313Z","end":"2026-06-10T15:46:50.237993Z","steps":["trace[655761061] 'process raft request' (duration: 332.455968ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:50.238114Z","caller":"traceutil/trace.go:171","msg":"trace[1080931123] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"332.163081ms","start":"2026-06-10T15:46:49.90592Z","end":"2026-06-10T15:46:50.238083Z","steps":["trace[1080931123] 'process raft request' (duration: 331.989648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:50.238165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:49.905304Z","time spent":"332.782344ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:46:50.238336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:49.905912Z","time spent":"332.263733ms","remote":"127.0.0.1:33550","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-06-10T15:46:50.677545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.443494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:50.677624Z","caller":"traceutil/trace.go:171","msg":"trace[263368820] linearizableReadLoop","detail":"{readStateIndex:25973; appliedIndex:25972; }","duration":"108.629222ms","start":"2026-06-10T15:46:50.568983Z","end":"2026-06-10T15:46:50.677613Z","steps":["trace[263368820] 'read index received' (duration: 42.65µs)","trace[263368820] 'applied index is now lower than readState.Index' (duration: 108.585842ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:50.677641Z","caller":"traceutil/trace.go:171","msg":"trace[376133156] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"245.154564ms","start":"2026-06-10T15:46:50.432463Z","end":"2026-06-10T15:46:50.677617Z","steps":["trace[376133156] 'process raft request' (duration: 62.548646ms)","trace[376133156] 'compare' (duration: 182.365253ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:50.677692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713704ms","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-06-10T15:46:50.67771Z","caller":"traceutil/trace.go:171","msg":"trace[1998281467] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25138; }","duration":"108.734994ms","start":"2026-06-10T15:46:50.568969Z","end":"2026-06-10T15:46:50.677704Z","steps":["trace[1998281467] 'agreement among raft nodes before linearized reading' (duration: 108.693114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.028708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.253715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:51.028811Z","caller":"traceutil/trace.go:171","msg":"trace[2026490461] linearizableReadLoop","detail":"{readStateIndex:25976; appliedIndex:25975; }","duration":"276.685096ms","start":"2026-06-10T15:46:50.75211Z","end":"2026-06-10T15:46:51.028795Z","steps":["trace[2026490461] 'read index received' (duration: 155.281508ms)","trace[2026490461] 'applied index is now lower than readState.Index' (duration: 121.402178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:51.029039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.935121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:51.028997Z","caller":"traceutil/trace.go:171","msg":"trace[2263992] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"306.723109ms","start":"2026-06-10T15:46:50.722221Z","end":"2026-06-10T15:46:51.028944Z","steps":["trace[2263992] 'process raft request' (duration: 185.150258ms)","trace[2263992] 'compare' (duration: 120.828597ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:51.029074Z","caller":"traceutil/trace.go:171","msg":"trace[1183875065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25141; }","duration":"277.006143ms","start":"2026-06-10T15:46:50.752058Z","end":"2026-06-10T15:46:51.029064Z","steps":["trace[1183875065] 'agreement among raft nodes before linearized reading' (duration: 276.945841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.029248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:50.722197Z","time spent":"306.888693ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:46:51.467375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.960147ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T15:46:51.468099Z","caller":"traceutil/trace.go:171","msg":"trace[2003889193] linearizableReadLoop","detail":"{readStateIndex:25978; appliedIndex:25976; }","duration":"439.243122ms","start":"2026-06-10T15:46:51.028836Z","end":"2026-06-10T15:46:51.46808Z","steps":["trace[2003889193] 'read index received' (duration: 114.665593ms)","trace[2003889193] 'applied index is now lower than readState.Index' (duration: 324.576169ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:51.468214Z","caller":"traceutil/trace.go:171","msg":"trace[1201990122] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"443.264153ms","start":"2026-06-10T15:46:51.024936Z","end":"2026-06-10T15:46:51.4682Z","steps":["trace[1201990122] 'process raft request' (duration: 442.550929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.468352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.024899Z","time spent":"443.356194ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:46:51.612207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.758735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-06-10T15:46:51.61227Z","caller":"traceutil/trace.go:171","msg":"trace[612452258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25142; }","duration":"349.873587ms","start":"2026-06-10T15:46:51.262381Z","end":"2026-06-10T15:46:51.612255Z","steps":["trace[612452258] 'agreement among raft nodes before linearized reading' (duration: 206.046838ms)","trace[612452258] 'range keys from in-memory index tree' (duration: 143.676256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:51.612299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.262356Z","time spent":"349.937118ms","remote":"127.0.0.1:33500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-06-10T15:46:51.612302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.798099ms","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-06-10T15:46:51.612391Z","caller":"traceutil/trace.go:171","msg":"trace[1724200710] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25142; }","duration":"734.936171ms","start":"2026-06-10T15:46:50.877431Z","end":"2026-06-10T15:46:51.612367Z","steps":["trace[1724200710] 'agreement among raft nodes before linearized reading' (duration: 591.02171ms)","trace[1724200710] 'range keys from in-memory index tree' (duration: 143.693146ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:51.612441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:50.877407Z","time spent":"735.024713ms","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-10T15:46:51.612493Z","caller":"traceutil/trace.go:171","msg":"trace[971140172] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"125.059852ms","start":"2026-06-10T15:46:51.487376Z","end":"2026-06-10T15:46:51.612436Z","steps":["trace[971140172] 'process raft request' (duration: 119.452979ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:51.917278Z","caller":"traceutil/trace.go:171","msg":"trace[2012142959] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25980; }","duration":"292.939724ms","start":"2026-06-10T15:46:51.624307Z","end":"2026-06-10T15:46:51.917247Z","steps":["trace[2012142959] 'read index received' (duration: 292.738839ms)","trace[2012142959] 'applied index is now lower than readState.Index' (duration: 199.635µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:51.917421Z","caller":"traceutil/trace.go:171","msg":"trace[1111486454] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"302.587197ms","start":"2026-06-10T15:46:51.614822Z","end":"2026-06-10T15:46:51.917409Z","steps":["trace[1111486454] 'process raft request' (duration: 302.237369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.917676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.470005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-06-10T15:46:51.91774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.614793Z","time spent":"302.656428ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:46:51.918202Z","caller":"traceutil/trace.go:171","msg":"trace[653877766] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25145; }","duration":"301.599908ms","start":"2026-06-10T15:46:51.616133Z","end":"2026-06-10T15:46:51.917733Z","steps":["trace[653877766] 'agreement among raft nodes before linearized reading' (duration: 301.354333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.918237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.616119Z","time spent":"302.112197ms","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-10T15:46:51.941588Z","caller":"traceutil/trace.go:171","msg":"trace[1816353991] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"244.176844ms","start":"2026-06-10T15:46:51.69739Z","end":"2026-06-10T15:46:51.941567Z","steps":["trace[1816353991] 'process raft request' (duration: 243.787046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.94185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.591314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:51.942012Z","caller":"traceutil/trace.go:171","msg":"trace[10567689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25147; }","duration":"276.791048ms","start":"2026-06-10T15:46:51.665207Z","end":"2026-06-10T15:46:51.941998Z","steps":["trace[10567689] 'agreement among raft nodes before linearized reading' (duration: 276.591124ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:51.942258Z","caller":"traceutil/trace.go:171","msg":"trace[454665950] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"242.144803ms","start":"2026-06-10T15:46:51.700101Z","end":"2026-06-10T15:46:51.942245Z","steps":["trace[454665950] 'process raft request' (duration: 241.209175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.942613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.883205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:51.94266Z","caller":"traceutil/trace.go:171","msg":"trace[1004434626] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25147; }","duration":"197.960156ms","start":"2026-06-10T15:46:51.744685Z","end":"2026-06-10T15:46:51.942645Z","steps":["trace[1004434626] 'agreement among raft nodes before linearized reading' (duration: 197.891405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.942857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.014609ms","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-06-10T15:46:51.942893Z","caller":"traceutil/trace.go:171","msg":"trace[1158342576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25147; }","duration":"223.082671ms","start":"2026-06-10T15:46:51.719802Z","end":"2026-06-10T15:46:51.942884Z","steps":["trace[1158342576] 'agreement among raft nodes before linearized reading' (duration: 222.995829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.943847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.220061ms","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-06-10T15:46:51.943932Z","caller":"traceutil/trace.go:171","msg":"trace[460162352] 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:25147; }","duration":"314.361443ms","start":"2026-06-10T15:46:51.629552Z","end":"2026-06-10T15:46:51.943913Z","steps":["trace[460162352] 'agreement among raft nodes before linearized reading' (duration: 314.20541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:51.943982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.629532Z","time spent":"314.439435ms","remote":"127.0.0.1:35498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-10T15:46:52.262146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.527873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:52.262249Z","caller":"traceutil/trace.go:171","msg":"trace[1177653258] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25983; }","duration":"256.991541ms","start":"2026-06-10T15:46:52.005238Z","end":"2026-06-10T15:46:52.262229Z","steps":["trace[1177653258] 'read index received' (duration: 17.121784ms)","trace[1177653258] 'applied index is now lower than readState.Index' (duration: 239.868347ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:52.262292Z","caller":"traceutil/trace.go:171","msg":"trace[1254781206] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"313.965285ms","start":"2026-06-10T15:46:51.948304Z","end":"2026-06-10T15:46:52.26227Z","steps":["trace[1254781206] 'process raft request' (duration: 172.239189ms)","trace[1254781206] 'compare' (duration: 141.006081ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:52.262404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:51.948284Z","time spent":"314.067258ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:46:52.262402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.160765ms","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-06-10T15:46:52.262557Z","caller":"traceutil/trace.go:171","msg":"trace[910297545] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25149; }","duration":"257.354669ms","start":"2026-06-10T15:46:52.005189Z","end":"2026-06-10T15:46:52.262544Z","steps":["trace[910297545] 'agreement among raft nodes before linearized reading' (duration: 257.116534ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:52.459508Z","caller":"traceutil/trace.go:171","msg":"trace[1610352825] linearizableReadLoop","detail":"{readStateIndex:25986; appliedIndex:25985; }","duration":"197.17845ms","start":"2026-06-10T15:46:52.262279Z","end":"2026-06-10T15:46:52.459457Z","steps":["trace[1610352825] 'read index received' (duration: 152.207687ms)","trace[1610352825] 'applied index is now lower than readState.Index' (duration: 44.969413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:52.459756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.067279ms","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-06-10T15:46:52.459786Z","caller":"traceutil/trace.go:171","msg":"trace[1209309669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25150; }","duration":"217.137581ms","start":"2026-06-10T15:46:52.242641Z","end":"2026-06-10T15:46:52.459779Z","steps":["trace[1209309669] 'agreement among raft nodes before linearized reading' (duration: 217.021818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:52.45983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.432646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T15:46:52.459865Z","caller":"traceutil/trace.go:171","msg":"trace[1295327503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25150; }","duration":"217.508998ms","start":"2026-06-10T15:46:52.242343Z","end":"2026-06-10T15:46:52.459852Z","steps":["trace[1295327503] 'agreement among raft nodes before linearized reading' (duration: 217.412546ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:52.459895Z","caller":"traceutil/trace.go:171","msg":"trace[1953651630] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"302.867083ms","start":"2026-06-10T15:46:52.157021Z","end":"2026-06-10T15:46:52.459888Z","steps":["trace[1953651630] 'process raft request' (duration: 257.473701ms)","trace[1953651630] 'compare' (duration: 44.660597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:52.459957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.156975Z","time spent":"302.935894ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:46:52.460035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.25747ms","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-06-10T15:46:52.460071Z","caller":"traceutil/trace.go:171","msg":"trace[1713899288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25150; }","duration":"309.329422ms","start":"2026-06-10T15:46:52.150733Z","end":"2026-06-10T15:46:52.460062Z","steps":["trace[1713899288] 'agreement among raft nodes before linearized reading' (duration: 309.24528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:52.460129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.15071Z","time spent":"309.381523ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T15:46:52.460346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.944675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:52.460385Z","caller":"traceutil/trace.go:171","msg":"trace[168680129] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25151; }","duration":"194.010166ms","start":"2026-06-10T15:46:52.266366Z","end":"2026-06-10T15:46:52.460376Z","steps":["trace[168680129] 'agreement among raft nodes before linearized reading' (duration: 193.939965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:52.754333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.194516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:46:52.754498Z","caller":"traceutil/trace.go:171","msg":"trace[1874790246] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"286.479363ms","start":"2026-06-10T15:46:52.468006Z","end":"2026-06-10T15:46:52.754485Z","steps":["trace[1874790246] 'process raft request' (duration: 286.436713ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:52.754721Z","caller":"traceutil/trace.go:171","msg":"trace[389332529] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"289.219369ms","start":"2026-06-10T15:46:52.465494Z","end":"2026-06-10T15:46:52.754713Z","steps":["trace[389332529] 'process raft request' (duration: 136.589704ms)","trace[389332529] 'compare' (duration: 151.685146ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:52.754808Z","caller":"traceutil/trace.go:171","msg":"trace[576951822] linearizableReadLoop","detail":"{readStateIndex:25988; appliedIndex:25987; }","duration":"288.792301ms","start":"2026-06-10T15:46:52.466009Z","end":"2026-06-10T15:46:52.754801Z","steps":["trace[576951822] 'read index received' (duration: 136.103213ms)","trace[576951822] 'applied index is now lower than readState.Index' (duration: 152.688468ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:52.754855Z","caller":"traceutil/trace.go:171","msg":"trace[539322924] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"288.092176ms","start":"2026-06-10T15:46:52.466756Z","end":"2026-06-10T15:46:52.754848Z","steps":["trace[539322924] 'process raft request' (duration: 287.650107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:52.755077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.065276ms","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-06-10T15:46:52.755103Z","caller":"traceutil/trace.go:171","msg":"trace[2007329794] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25154; }","duration":"289.103647ms","start":"2026-06-10T15:46:52.465993Z","end":"2026-06-10T15:46:52.755096Z","steps":["trace[2007329794] 'agreement among raft nodes before linearized reading' (duration: 288.978995ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:53.036065Z","caller":"traceutil/trace.go:171","msg":"trace[255210964] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"343.151532ms","start":"2026-06-10T15:46:52.692875Z","end":"2026-06-10T15:46:53.036026Z","steps":["trace[255210964] 'process raft request' (duration: 255.836439ms)","trace[255210964] 'compare' (duration: 87.13514ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:46:53.036269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.692848Z","time spent":"343.317156ms","remote":"127.0.0.1:33550","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-06-10T15:46:53.050944Z","caller":"traceutil/trace.go:171","msg":"trace[443735126] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25990; }","duration":"296.091916ms","start":"2026-06-10T15:46:52.754825Z","end":"2026-06-10T15:46:53.050917Z","steps":["trace[443735126] 'read index received' (duration: 193.851573ms)","trace[443735126] 'applied index is now lower than readState.Index' (duration: 102.239653ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:46:53.050969Z","caller":"traceutil/trace.go:171","msg":"trace[420614933] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"357.726885ms","start":"2026-06-10T15:46:52.693215Z","end":"2026-06-10T15:46:53.050942Z","steps":["trace[420614933] 'process raft request' (duration: 357.530901ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:53.051075Z","caller":"traceutil/trace.go:171","msg":"trace[35985504] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"291.443603ms","start":"2026-06-10T15:46:52.759616Z","end":"2026-06-10T15:46:53.051059Z","steps":["trace[35985504] 'process raft request' (duration: 291.270739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:53.05134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.57911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:46:53.051356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.799528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:46:53.051373Z","caller":"traceutil/trace.go:171","msg":"trace[1052476491] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25157; }","duration":"218.650822ms","start":"2026-06-10T15:46:52.832715Z","end":"2026-06-10T15:46:53.051366Z","steps":["trace[1052476491] 'agreement among raft nodes before linearized reading' (duration: 218.525189ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:46:53.051377Z","caller":"traceutil/trace.go:171","msg":"trace[1392720479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25157; }","duration":"384.860499ms","start":"2026-06-10T15:46:52.66651Z","end":"2026-06-10T15:46:53.051371Z","steps":["trace[1392720479] 'agreement among raft nodes before linearized reading' (duration: 384.817748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:53.0514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.753099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"warn","ts":"2026-06-10T15:46:53.051399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.666486Z","time spent":"384.90624ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T15:46:53.051444Z","caller":"traceutil/trace.go:171","msg":"trace[1681268788] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25157; }","duration":"292.82975ms","start":"2026-06-10T15:46:52.758604Z","end":"2026-06-10T15:46:53.051434Z","steps":["trace[1681268788] 'agreement among raft nodes before linearized reading' (duration: 292.627596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:53.051729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.514426ms","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-06-10T15:46:53.051759Z","caller":"traceutil/trace.go:171","msg":"trace[2117250095] 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:25157; }","duration":"530.581037ms","start":"2026-06-10T15:46:52.521169Z","end":"2026-06-10T15:46:53.05175Z","steps":["trace[2117250095] 'agreement among raft nodes before linearized reading' (duration: 530.104127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:46:53.051775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.521143Z","time spent":"530.627768ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-06-10T15:46:53.05207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:46:52.693189Z","time spent":"357.837996ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:02.536417Z","caller":"traceutil/trace.go:171","msg":"trace[1255891152] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"231.398817ms","start":"2026-06-10T15:47:02.304999Z","end":"2026-06-10T15:47:02.536398Z","steps":["trace[1255891152] 'process raft request' (duration: 231.273114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:02.827427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.945964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:02.827789Z","caller":"traceutil/trace.go:171","msg":"trace[1335982681] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"331.416766ms","start":"2026-06-10T15:47:02.496342Z","end":"2026-06-10T15:47:02.827759Z","steps":["trace[1335982681] 'process raft request' (duration: 331.284804ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:02.827851Z","caller":"traceutil/trace.go:171","msg":"trace[427299398] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"350.083131ms","start":"2026-06-10T15:47:02.477723Z","end":"2026-06-10T15:47:02.827806Z","steps":["trace[427299398] 'process raft request' (duration: 163.706829ms)","trace[427299398] 'compare' (duration: 185.813851ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:02.82792Z","caller":"traceutil/trace.go:171","msg":"trace[1163359062] linearizableReadLoop","detail":"{readStateIndex:26066; appliedIndex:26064; }","duration":"280.619376ms","start":"2026-06-10T15:47:02.547078Z","end":"2026-06-10T15:47:02.827697Z","steps":["trace[1163359062] 'read index received' (duration: 94.359306ms)","trace[1163359062] 'applied index is now lower than readState.Index' (duration: 186.25915ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:02.827932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:02.496326Z","time spent":"331.526908ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:02.827963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.8572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:47:02.827985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:02.477703Z","time spent":"350.207643ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:02.828042Z","caller":"traceutil/trace.go:171","msg":"trace[430370637] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25228; }","duration":"280.999083ms","start":"2026-06-10T15:47:02.547022Z","end":"2026-06-10T15:47:02.828021Z","steps":["trace[430370637] 'agreement among raft nodes before linearized reading' (duration: 280.81135ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.102711Z","caller":"traceutil/trace.go:171","msg":"trace[956977069] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"271.008442ms","start":"2026-06-10T15:47:02.831685Z","end":"2026-06-10T15:47:03.102694Z","steps":["trace[956977069] 'process raft request' (duration: 270.91389ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.401287Z","caller":"traceutil/trace.go:171","msg":"trace[408713695] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"568.703412ms","start":"2026-06-10T15:47:02.832551Z","end":"2026-06-10T15:47:03.401254Z","steps":["trace[408713695] 'process raft request' (duration: 568.473667ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.40136Z","caller":"traceutil/trace.go:171","msg":"trace[134427853] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26067; }","duration":"222.675221ms","start":"2026-06-10T15:47:03.178665Z","end":"2026-06-10T15:47:03.40134Z","steps":["trace[134427853] 'read index received' (duration: 222.349225ms)","trace[134427853] 'applied index is now lower than readState.Index' (duration: 324.776µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:03.401478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:02.832533Z","time spent":"568.844294ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:03.401514Z","caller":"traceutil/trace.go:171","msg":"trace[1258614599] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"310.415945ms","start":"2026-06-10T15:47:03.091087Z","end":"2026-06-10T15:47:03.401503Z","steps":["trace[1258614599] 'process raft request' (duration: 310.133689ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.401533Z","caller":"traceutil/trace.go:171","msg":"trace[108564321] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"330.215783ms","start":"2026-06-10T15:47:03.071286Z","end":"2026-06-10T15:47:03.401502Z","steps":["trace[108564321] 'process raft request' (duration: 329.901406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:03.401598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:03.091059Z","time spent":"310.479575ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:03.401632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:03.071259Z","time spent":"330.325234ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:03.401649Z","caller":"traceutil/trace.go:171","msg":"trace[2030851710] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"309.957915ms","start":"2026-06-10T15:47:03.091677Z","end":"2026-06-10T15:47:03.401635Z","steps":["trace[2030851710] 'process raft request' (duration: 309.622299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:03.40172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:03.091644Z","time spent":"310.038367ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:03.401711Z","caller":"traceutil/trace.go:171","msg":"trace[1630244635] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"569.057989ms","start":"2026-06-10T15:47:02.832554Z","end":"2026-06-10T15:47:03.401612Z","steps":["trace[1630244635] 'process raft request' (duration: 568.59903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:03.401919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:02.832532Z","time spent":"569.305284ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:03.402237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.563159ms","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-06-10T15:47:03.402288Z","caller":"traceutil/trace.go:171","msg":"trace[1009276106] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25234; }","duration":"223.651581ms","start":"2026-06-10T15:47:03.178624Z","end":"2026-06-10T15:47:03.402275Z","steps":["trace[1009276106] 'agreement among raft nodes before linearized reading' (duration: 222.793844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.826838Z","caller":"traceutil/trace.go:171","msg":"trace[1501458892] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"297.872672ms","start":"2026-06-10T15:47:03.52893Z","end":"2026-06-10T15:47:03.826802Z","steps":["trace[1501458892] 'process raft request' (duration: 200.289072ms)","trace[1501458892] 'compare' (duration: 96.944547ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:03.826886Z","caller":"traceutil/trace.go:171","msg":"trace[2066611299] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"172.990085ms","start":"2026-06-10T15:47:03.653867Z","end":"2026-06-10T15:47:03.826858Z","steps":["trace[2066611299] 'process raft request' (duration: 172.794591ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.826952Z","caller":"traceutil/trace.go:171","msg":"trace[616573469] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"169.134686ms","start":"2026-06-10T15:47:03.657796Z","end":"2026-06-10T15:47:03.826931Z","steps":["trace[616573469] 'process raft request' (duration: 168.956083ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.826983Z","caller":"traceutil/trace.go:171","msg":"trace[1681003998] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"164.505455ms","start":"2026-06-10T15:47:03.662444Z","end":"2026-06-10T15:47:03.826949Z","steps":["trace[1681003998] 'process raft request' (duration: 164.382612ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:03.827165Z","caller":"traceutil/trace.go:171","msg":"trace[1622545542] linearizableReadLoop","detail":"{readStateIndex:26076; appliedIndex:26072; }","duration":"162.138067ms","start":"2026-06-10T15:47:03.664767Z","end":"2026-06-10T15:47:03.826905Z","steps":["trace[1622545542] 'read index received' (duration: 64.463945ms)","trace[1622545542] 'applied index is now lower than readState.Index' (duration: 97.672712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:03.827175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.392021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:03.827231Z","caller":"traceutil/trace.go:171","msg":"trace[1550827948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25238; }","duration":"162.478884ms","start":"2026-06-10T15:47:03.664738Z","end":"2026-06-10T15:47:03.827217Z","steps":["trace[1550827948] 'agreement among raft nodes before linearized reading' (duration: 162.346711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:03.828281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.213145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:47:03.828337Z","caller":"traceutil/trace.go:171","msg":"trace[116314681] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25238; }","duration":"133.308767ms","start":"2026-06-10T15:47:03.695014Z","end":"2026-06-10T15:47:03.828323Z","steps":["trace[116314681] 'agreement among raft nodes before linearized reading' (duration: 132.524681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:04.378471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.317746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:04.3786Z","caller":"traceutil/trace.go:171","msg":"trace[217041425] linearizableReadLoop","detail":"{readStateIndex:26077; appliedIndex:26076; }","duration":"297.24369ms","start":"2026-06-10T15:47:04.08134Z","end":"2026-06-10T15:47:04.378583Z","steps":["trace[217041425] 'read index received' (duration: 52.586106ms)","trace[217041425] 'applied index is now lower than readState.Index' (duration: 244.656014ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:04.378845Z","caller":"traceutil/trace.go:171","msg":"trace[1597445113] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"382.648695ms","start":"2026-06-10T15:47:03.99601Z","end":"2026-06-10T15:47:04.378659Z","steps":["trace[1597445113] 'process raft request' (duration: 137.996122ms)","trace[1597445113] 'compare' (duration: 244.071182ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:04.378965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.615847ms","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-06-10T15:47:04.379005Z","caller":"traceutil/trace.go:171","msg":"trace[922089848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25239; }","duration":"297.706309ms","start":"2026-06-10T15:47:04.081288Z","end":"2026-06-10T15:47:04.378995Z","steps":["trace[922089848] 'agreement among raft nodes before linearized reading' (duration: 297.340482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:04.380625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:03.995979Z","time spent":"382.979252ms","remote":"127.0.0.1:33550","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-06-10T15:47:04.928456Z","caller":"traceutil/trace.go:171","msg":"trace[1958281019] linearizableReadLoop","detail":"{readStateIndex:26078; appliedIndex:26077; }","duration":"387.307588ms","start":"2026-06-10T15:47:04.541112Z","end":"2026-06-10T15:47:04.92842Z","steps":["trace[1958281019] 'read index received' (duration: 352.467129ms)","trace[1958281019] 'applied index is now lower than readState.Index' (duration: 34.839049ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:04.92848Z","caller":"traceutil/trace.go:171","msg":"trace[2097909803] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"538.998385ms","start":"2026-06-10T15:47:04.389433Z","end":"2026-06-10T15:47:04.928431Z","steps":["trace[2097909803] 'process raft request' (duration: 504.125275ms)","trace[2097909803] 'compare' (duration: 34.356509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:04.928698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:04.389414Z","time spent":"539.144298ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:04.92875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.614694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T15:47:04.928837Z","caller":"traceutil/trace.go:171","msg":"trace[1963789572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25240; }","duration":"387.739647ms","start":"2026-06-10T15:47:04.541075Z","end":"2026-06-10T15:47:04.928815Z","steps":["trace[1963789572] 'agreement among raft nodes before linearized reading' (duration: 387.472862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:04.928878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:04.541056Z","time spent":"387.813688ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T15:47:04.931598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.451277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:04.931659Z","caller":"traceutil/trace.go:171","msg":"trace[540082943] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25241; }","duration":"332.518228ms","start":"2026-06-10T15:47:04.599125Z","end":"2026-06-10T15:47:04.931644Z","steps":["trace[540082943] 'agreement among raft nodes before linearized reading' (duration: 332.406966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:04.9317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:04.599064Z","time spent":"332.62699ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-06-10T15:47:04.931909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.050253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:04.931947Z","caller":"traceutil/trace.go:171","msg":"trace[1550670073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25241; }","duration":"266.125075ms","start":"2026-06-10T15:47:04.665812Z","end":"2026-06-10T15:47:04.931937Z","steps":["trace[1550670073] 'agreement among raft nodes before linearized reading' (duration: 266.067673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:04.932269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.559023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:04.932373Z","caller":"traceutil/trace.go:171","msg":"trace[1269496050] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25241; }","duration":"273.716268ms","start":"2026-06-10T15:47:04.658633Z","end":"2026-06-10T15:47:04.932349Z","steps":["trace[1269496050] 'agreement among raft nodes before linearized reading' (duration: 273.526573ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:07.442527Z","caller":"traceutil/trace.go:171","msg":"trace[1278642394] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"231.556161ms","start":"2026-06-10T15:47:07.210949Z","end":"2026-06-10T15:47:07.442505Z","steps":["trace[1278642394] 'process raft request' (duration: 231.394878ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:07.640551Z","caller":"traceutil/trace.go:171","msg":"trace[359884370] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"228.985948ms","start":"2026-06-10T15:47:07.411537Z","end":"2026-06-10T15:47:07.640523Z","steps":["trace[359884370] 'process raft request' (duration: 208.299613ms)","trace[359884370] 'compare' (duration: 20.516382ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:07.640758Z","caller":"traceutil/trace.go:171","msg":"trace[1914824269] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"225.420067ms","start":"2026-06-10T15:47:07.41532Z","end":"2026-06-10T15:47:07.64074Z","steps":["trace[1914824269] 'process raft request' (duration: 225.161762ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:07.641058Z","caller":"traceutil/trace.go:171","msg":"trace[2085576515] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"224.156222ms","start":"2026-06-10T15:47:07.416868Z","end":"2026-06-10T15:47:07.641024Z","steps":["trace[2085576515] 'process raft request' (duration: 223.815176ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:07.641032Z","caller":"traceutil/trace.go:171","msg":"trace[725952103] linearizableReadLoop","detail":"{readStateIndex:26158; appliedIndex:26155; }","duration":"225.381486ms","start":"2026-06-10T15:47:07.415619Z","end":"2026-06-10T15:47:07.641001Z","steps":["trace[725952103] 'read index received' (duration: 26.817189ms)","trace[725952103] 'applied index is now lower than readState.Index' (duration: 198.562567ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:07.641238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.604111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-10T15:47:07.641282Z","caller":"traceutil/trace.go:171","msg":"trace[2088815728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25319; }","duration":"225.696303ms","start":"2026-06-10T15:47:07.415574Z","end":"2026-06-10T15:47:07.641271Z","steps":["trace[2088815728] 'agreement among raft nodes before linearized reading' (duration: 225.544369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:07.64154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.190543ms","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-06-10T15:47:07.641598Z","caller":"traceutil/trace.go:171","msg":"trace[1523032111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25319; }","duration":"223.304695ms","start":"2026-06-10T15:47:07.418282Z","end":"2026-06-10T15:47:07.641586Z","steps":["trace[1523032111] 'agreement among raft nodes before linearized reading' (duration: 223.085031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:09.452673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.308122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T15:47:09.452875Z","caller":"traceutil/trace.go:171","msg":"trace[1391534331] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25331; }","duration":"184.483065ms","start":"2026-06-10T15:47:09.2683Z","end":"2026-06-10T15:47:09.452783Z","steps":["trace[1391534331] 'range keys from in-memory index tree' (duration: 184.057197ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:14.555331Z","caller":"traceutil/trace.go:171","msg":"trace[775942872] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"223.839456ms","start":"2026-06-10T15:47:14.331458Z","end":"2026-06-10T15:47:14.555297Z","steps":["trace[775942872] 'process raft request' (duration: 223.51609ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:14.555845Z","caller":"traceutil/trace.go:171","msg":"trace[2112650736] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26231; }","duration":"198.087739ms","start":"2026-06-10T15:47:14.357715Z","end":"2026-06-10T15:47:14.555803Z","steps":["trace[2112650736] 'read index received' (duration: 198.068289ms)","trace[2112650736] 'applied index is now lower than readState.Index' (duration: 16.16µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:14.556201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.441306ms","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-06-10T15:47:14.556307Z","caller":"traceutil/trace.go:171","msg":"trace[675653208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25389; }","duration":"198.578829ms","start":"2026-06-10T15:47:14.357704Z","end":"2026-06-10T15:47:14.556283Z","steps":["trace[675653208] 'agreement among raft nodes before linearized reading' (duration: 198.223512ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:14.557411Z","caller":"traceutil/trace.go:171","msg":"trace[402573353] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"147.058933ms","start":"2026-06-10T15:47:14.410325Z","end":"2026-06-10T15:47:14.557384Z","steps":["trace[402573353] 'process raft request' (duration: 146.516953ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:14.701293Z","caller":"traceutil/trace.go:171","msg":"trace[1974738682] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"138.36054ms","start":"2026-06-10T15:47:14.5629Z","end":"2026-06-10T15:47:14.70126Z","steps":["trace[1974738682] 'process raft request' (duration: 46.481784ms)","trace[1974738682] 'compare' (duration: 91.6116ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:14.954095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.621683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:14.954283Z","caller":"traceutil/trace.go:171","msg":"trace[1736119039] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"390.826268ms","start":"2026-06-10T15:47:14.563427Z","end":"2026-06-10T15:47:14.954253Z","steps":["trace[1736119039] 'process raft request' (duration: 252.95519ms)","trace[1736119039] 'compare' (duration: 137.475221ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:14.95432Z","caller":"traceutil/trace.go:171","msg":"trace[71234946] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"390.753247ms","start":"2026-06-10T15:47:14.563548Z","end":"2026-06-10T15:47:14.954301Z","steps":["trace[71234946] 'process raft request' (duration: 390.641085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:14.954408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:14.5634Z","time spent":"390.937191ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:14.954389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:14.563542Z","time spent":"390.814588ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T15:47:14.954495Z","caller":"traceutil/trace.go:171","msg":"trace[218292634] linearizableReadLoop","detail":"{readStateIndex:26237; appliedIndex:26234; }","duration":"285.145307ms","start":"2026-06-10T15:47:14.669324Z","end":"2026-06-10T15:47:14.95447Z","steps":["trace[218292634] 'read index received' (duration: 147.069444ms)","trace[218292634] 'applied index is now lower than readState.Index' (duration: 138.074523ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:14.954559Z","caller":"traceutil/trace.go:171","msg":"trace[271688282] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"390.694336ms","start":"2026-06-10T15:47:14.563833Z","end":"2026-06-10T15:47:14.954527Z","steps":["trace[271688282] 'process raft request' (duration: 390.42007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:14.954618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.281409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:14.954656Z","caller":"traceutil/trace.go:171","msg":"trace[506255090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25395; }","duration":"285.364771ms","start":"2026-06-10T15:47:14.669281Z","end":"2026-06-10T15:47:14.954646Z","steps":["trace[506255090] 'agreement among raft nodes before linearized reading' (duration: 285.28545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:14.95467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.163315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T15:47:14.954718Z","caller":"traceutil/trace.go:171","msg":"trace[1769343408] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25395; }","duration":"261.242306ms","start":"2026-06-10T15:47:14.693464Z","end":"2026-06-10T15:47:14.954707Z","steps":["trace[1769343408] 'agreement among raft nodes before linearized reading' (duration: 261.143004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:14.95473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T15:47:14.563803Z","time spent":"390.826059ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T15:47:14.954744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.441438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:14.954782Z","caller":"traceutil/trace.go:171","msg":"trace[378250813] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25395; }","duration":"112.53072ms","start":"2026-06-10T15:47:14.84224Z","end":"2026-06-10T15:47:14.95477Z","steps":["trace[378250813] 'agreement among raft nodes before linearized reading' (duration: 112.439878ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:15.08937Z","caller":"traceutil/trace.go:171","msg":"trace[218795686] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"102.526458ms","start":"2026-06-10T15:47:14.986818Z","end":"2026-06-10T15:47:15.089344Z","steps":["trace[218795686] 'read index received' (duration: 99.823094ms)","trace[218795686] 'applied index is now lower than readState.Index' (duration: 2.701984ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:15.089493Z","caller":"traceutil/trace.go:171","msg":"trace[1320358005] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"125.729296ms","start":"2026-06-10T15:47:14.963729Z","end":"2026-06-10T15:47:15.089458Z","steps":["trace[1320358005] 'process raft request' (duration: 122.97026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:15.08963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.791573ms","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-06-10T15:47:15.089678Z","caller":"traceutil/trace.go:171","msg":"trace[1928357384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25396; }","duration":"102.895256ms","start":"2026-06-10T15:47:14.986766Z","end":"2026-06-10T15:47:15.089661Z","steps":["trace[1928357384] 'agreement among raft nodes before linearized reading' (duration: 102.724722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:22.169681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.948174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:22.169871Z","caller":"traceutil/trace.go:171","msg":"trace[1577710836] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"277.781679ms","start":"2026-06-10T15:47:21.892072Z","end":"2026-06-10T15:47:22.169853Z","steps":["trace[1577710836] 'process raft request' (duration: 277.705057ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:22.169885Z","caller":"traceutil/trace.go:171","msg":"trace[228830191] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"278.225319ms","start":"2026-06-10T15:47:21.891631Z","end":"2026-06-10T15:47:22.169856Z","steps":["trace[228830191] 'process raft request' (duration: 106.014579ms)","trace[228830191] 'compare' (duration: 171.767471ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:22.20383Z","caller":"traceutil/trace.go:171","msg":"trace[1832945104] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"140.659705ms","start":"2026-06-10T15:47:22.063139Z","end":"2026-06-10T15:47:22.203798Z","steps":["trace[1832945104] 'process raft request' (duration: 140.485762ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:22.773254Z","caller":"traceutil/trace.go:171","msg":"trace[996629678] linearizableReadLoop","detail":"{readStateIndex:26304; appliedIndex:26303; }","duration":"149.740637ms","start":"2026-06-10T15:47:22.623495Z","end":"2026-06-10T15:47:22.773236Z","steps":["trace[996629678] 'read index received' (duration: 149.594084ms)","trace[996629678] 'applied index is now lower than readState.Index' (duration: 145.923µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:22.773343Z","caller":"traceutil/trace.go:171","msg":"trace[646521913] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"189.318091ms","start":"2026-06-10T15:47:22.583998Z","end":"2026-06-10T15:47:22.773317Z","steps":["trace[646521913] 'process raft request' (duration: 189.103347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:22.773372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.830656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T15:47:22.773375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.876749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:22.773397Z","caller":"traceutil/trace.go:171","msg":"trace[993192507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25460; }","duration":"106.902317ms","start":"2026-06-10T15:47:22.666487Z","end":"2026-06-10T15:47:22.773389Z","steps":["trace[993192507] 'agreement among raft nodes before linearized reading' (duration: 106.843496ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:22.773414Z","caller":"traceutil/trace.go:171","msg":"trace[1127971325] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25460; }","duration":"149.92131ms","start":"2026-06-10T15:47:22.62348Z","end":"2026-06-10T15:47:22.773401Z","steps":["trace[1127971325] 'agreement among raft nodes before linearized reading' (duration: 149.844019ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:23.228939Z","caller":"traceutil/trace.go:171","msg":"trace[2002000566] linearizableReadLoop","detail":"{readStateIndex:26306; appliedIndex:26305; }","duration":"100.892186ms","start":"2026-06-10T15:47:23.128021Z","end":"2026-06-10T15:47:23.228913Z","steps":["trace[2002000566] 'read index received' (duration: 100.5994ms)","trace[2002000566] 'applied index is now lower than readState.Index' (duration: 291.426µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:23.229146Z","caller":"traceutil/trace.go:171","msg":"trace[2097087572] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"114.685074ms","start":"2026-06-10T15:47:23.114426Z","end":"2026-06-10T15:47:23.229111Z","steps":["trace[2097087572] 'process raft request' (duration: 114.306886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:23.229198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.146262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T15:47:23.229429Z","caller":"traceutil/trace.go:171","msg":"trace[389417672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25462; }","duration":"101.428117ms","start":"2026-06-10T15:47:23.127981Z","end":"2026-06-10T15:47:23.229409Z","steps":["trace[389417672] 'agreement among raft nodes before linearized reading' (duration: 101.07662ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:23.339933Z","caller":"traceutil/trace.go:171","msg":"trace[419844676] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"105.887505ms","start":"2026-06-10T15:47:23.234013Z","end":"2026-06-10T15:47:23.339901Z","steps":["trace[419844676] 'process raft request' (duration: 87.954686ms)","trace[419844676] 'compare' (duration: 17.619253ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:25.979521Z","caller":"traceutil/trace.go:171","msg":"trace[1314951974] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26326; }","duration":"177.135829ms","start":"2026-06-10T15:47:25.802355Z","end":"2026-06-10T15:47:25.979491Z","steps":["trace[1314951974] 'read index received' (duration: 20.481433ms)","trace[1314951974] 'applied index is now lower than readState.Index' (duration: 156.653286ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:25.979549Z","caller":"traceutil/trace.go:171","msg":"trace[1302249075] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"189.885694ms","start":"2026-06-10T15:47:25.789627Z","end":"2026-06-10T15:47:25.979512Z","steps":["trace[1302249075] 'process raft request' (duration: 139.34165ms)","trace[1302249075] 'compare' (duration: 50.236698ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:25.979591Z","caller":"traceutil/trace.go:171","msg":"trace[1401168430] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"188.772162ms","start":"2026-06-10T15:47:25.790771Z","end":"2026-06-10T15:47:25.979543Z","steps":["trace[1401168430] 'process raft request' (duration: 188.597719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:25.979789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.421064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-10T15:47:25.979791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.76116ms","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-06-10T15:47:25.979837Z","caller":"traceutil/trace.go:171","msg":"trace[191865694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25485; }","duration":"177.525277ms","start":"2026-06-10T15:47:25.802297Z","end":"2026-06-10T15:47:25.979822Z","steps":["trace[191865694] 'agreement among raft nodes before linearized reading' (duration: 177.340223ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:25.979843Z","caller":"traceutil/trace.go:171","msg":"trace[1366551904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25485; }","duration":"176.849332ms","start":"2026-06-10T15:47:25.802979Z","end":"2026-06-10T15:47:25.979828Z","steps":["trace[1366551904] 'agreement among raft nodes before linearized reading' (duration: 176.717059ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:29.461395Z","caller":"traceutil/trace.go:171","msg":"trace[1958029353] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"137.821558ms","start":"2026-06-10T15:47:29.323537Z","end":"2026-06-10T15:47:29.461359Z","steps":["trace[1958029353] 'process raft request' (duration: 48.684668ms)","trace[1958029353] 'compare' (duration: 88.548398ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:32.750652Z","caller":"traceutil/trace.go:171","msg":"trace[1616263783] linearizableReadLoop","detail":"{readStateIndex:26386; appliedIndex:26385; }","duration":"128.429169ms","start":"2026-06-10T15:47:32.622198Z","end":"2026-06-10T15:47:32.750627Z","steps":["trace[1616263783] 'read index received' (duration: 58.322191ms)","trace[1616263783] 'applied index is now lower than readState.Index' (duration: 70.105738ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:32.750668Z","caller":"traceutil/trace.go:171","msg":"trace[1267839336] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"138.256597ms","start":"2026-06-10T15:47:32.612381Z","end":"2026-06-10T15:47:32.750638Z","steps":["trace[1267839336] 'process raft request' (duration: 68.140579ms)","trace[1267839336] 'compare' (duration: 69.971766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:32.75085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.633803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-06-10T15:47:32.750901Z","caller":"traceutil/trace.go:171","msg":"trace[1761262729] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25540; }","duration":"128.707655ms","start":"2026-06-10T15:47:32.622179Z","end":"2026-06-10T15:47:32.750886Z","steps":["trace[1761262729] 'agreement among raft nodes before linearized reading' (duration: 128.517342ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:32.92491Z","caller":"traceutil/trace.go:171","msg":"trace[1023319800] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"116.923749ms","start":"2026-06-10T15:47:32.807963Z","end":"2026-06-10T15:47:32.924887Z","steps":["trace[1023319800] 'process raft request' (duration: 116.849548ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:32.925061Z","caller":"traceutil/trace.go:171","msg":"trace[1385650716] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"167.916623ms","start":"2026-06-10T15:47:32.757109Z","end":"2026-06-10T15:47:32.925026Z","steps":["trace[1385650716] 'process raft request' (duration: 110.936489ms)","trace[1385650716] 'compare' (duration: 56.614707ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:32.925102Z","caller":"traceutil/trace.go:171","msg":"trace[1727776583] linearizableReadLoop","detail":"{readStateIndex:26387; appliedIndex:26386; }","duration":"166.069735ms","start":"2026-06-10T15:47:32.759006Z","end":"2026-06-10T15:47:32.925075Z","steps":["trace[1727776583] 'read index received' (duration: 109.05492ms)","trace[1727776583] 'applied index is now lower than readState.Index' (duration: 57.012135ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:32.925304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.27976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10671"} {"level":"info","ts":"2026-06-10T15:47:32.925339Z","caller":"traceutil/trace.go:171","msg":"trace[85338702] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25542; }","duration":"166.358011ms","start":"2026-06-10T15:47:32.75897Z","end":"2026-06-10T15:47:32.925328Z","steps":["trace[85338702] 'agreement among raft nodes before linearized reading' (duration: 166.190377ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:37.433692Z","caller":"traceutil/trace.go:171","msg":"trace[1117237416] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"115.968248ms","start":"2026-06-10T15:47:37.317703Z","end":"2026-06-10T15:47:37.433672Z","steps":["trace[1117237416] 'process raft request' (duration: 115.842876ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:38.250394Z","caller":"traceutil/trace.go:171","msg":"trace[1474923746] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"115.343487ms","start":"2026-06-10T15:47:38.135029Z","end":"2026-06-10T15:47:38.250373Z","steps":["trace[1474923746] 'process raft request' (duration: 82.215681ms)","trace[1474923746] 'compare' (duration: 32.87393ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:38.250536Z","caller":"traceutil/trace.go:171","msg":"trace[711839368] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"115.112232ms","start":"2026-06-10T15:47:38.135393Z","end":"2026-06-10T15:47:38.250505Z","steps":["trace[711839368] 'process raft request' (duration: 114.883078ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:40.025653Z","caller":"traceutil/trace.go:171","msg":"trace[44760490] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"114.284595ms","start":"2026-06-10T15:47:39.911341Z","end":"2026-06-10T15:47:40.025625Z","steps":["trace[44760490] 'process raft request' (duration: 114.03503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:40.291971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.486187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:40.292104Z","caller":"traceutil/trace.go:171","msg":"trace[2120614647] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25597; }","duration":"210.708152ms","start":"2026-06-10T15:47:40.081368Z","end":"2026-06-10T15:47:40.292076Z","steps":["trace[2120614647] 'count revisions from in-memory index tree' (duration: 210.353644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:40.292806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.926591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:40.293044Z","caller":"traceutil/trace.go:171","msg":"trace[724261506] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"200.777391ms","start":"2026-06-10T15:47:40.092247Z","end":"2026-06-10T15:47:40.293024Z","steps":["trace[724261506] 'process raft request' (duration: 45.283099ms)","trace[724261506] 'compare' (duration: 154.539253ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:44.280102Z","caller":"traceutil/trace.go:171","msg":"trace[1989403660] linearizableReadLoop","detail":"{readStateIndex:26474; appliedIndex:26473; }","duration":"225.232463ms","start":"2026-06-10T15:47:44.054842Z","end":"2026-06-10T15:47:44.280074Z","steps":["trace[1989403660] 'read index received' (duration: 225.014859ms)","trace[1989403660] 'applied index is now lower than readState.Index' (duration: 216.724µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:44.290062Z","caller":"traceutil/trace.go:171","msg":"trace[1603911334] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"240.415998ms","start":"2026-06-10T15:47:44.039718Z","end":"2026-06-10T15:47:44.280134Z","steps":["trace[1603911334] 'process raft request' (duration: 240.197603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:44.290249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.366597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:44.290382Z","caller":"traceutil/trace.go:171","msg":"trace[416002526] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25626; }","duration":"235.53534ms","start":"2026-06-10T15:47:44.054783Z","end":"2026-06-10T15:47:44.290319Z","steps":["trace[416002526] 'agreement among raft nodes before linearized reading' (duration: 235.234324ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:44.455993Z","caller":"traceutil/trace.go:171","msg":"trace[1497837064] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"152.597074ms","start":"2026-06-10T15:47:44.303361Z","end":"2026-06-10T15:47:44.455958Z","steps":["trace[1497837064] 'process raft request' (duration: 66.142028ms)","trace[1497837064] 'compare' (duration: 86.304653ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:44.457078Z","caller":"traceutil/trace.go:171","msg":"trace[932219322] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"130.865569ms","start":"2026-06-10T15:47:44.326189Z","end":"2026-06-10T15:47:44.457055Z","steps":["trace[932219322] 'process raft request' (duration: 130.706525ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:50.814981Z","caller":"traceutil/trace.go:171","msg":"trace[65362691] linearizableReadLoop","detail":"{readStateIndex:26526; appliedIndex:26525; }","duration":"201.091569ms","start":"2026-06-10T15:47:50.61387Z","end":"2026-06-10T15:47:50.814962Z","steps":["trace[65362691] 'read index received' (duration: 130.807497ms)","trace[65362691] 'applied index is now lower than readState.Index' (duration: 70.283312ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:50.815125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.598494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:50.815092Z","caller":"traceutil/trace.go:171","msg":"trace[126790538] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"246.216265ms","start":"2026-06-10T15:47:50.568835Z","end":"2026-06-10T15:47:50.815051Z","steps":["trace[126790538] 'process raft request' (duration: 175.905943ms)","trace[126790538] 'compare' (duration: 70.134298ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T15:47:50.815244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.352664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-10T15:47:50.815155Z","caller":"traceutil/trace.go:171","msg":"trace[1537472606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25677; }","duration":"148.674486ms","start":"2026-06-10T15:47:50.666474Z","end":"2026-06-10T15:47:50.815148Z","steps":["trace[1537472606] 'agreement among raft nodes before linearized reading' (duration: 148.587424ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:50.815323Z","caller":"traceutil/trace.go:171","msg":"trace[1574478293] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25677; }","duration":"201.471187ms","start":"2026-06-10T15:47:50.613836Z","end":"2026-06-10T15:47:50.815307Z","steps":["trace[1574478293] 'agreement among raft nodes before linearized reading' (duration: 201.213392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:52.558905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.822796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T15:47:52.559017Z","caller":"traceutil/trace.go:171","msg":"trace[2009305813] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"242.132104ms","start":"2026-06-10T15:47:52.316863Z","end":"2026-06-10T15:47:52.558995Z","steps":["trace[2009305813] 'process raft request' (duration: 131.097444ms)","trace[2009305813] 'compare' (duration: 110.706253ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:52.570564Z","caller":"traceutil/trace.go:171","msg":"trace[1346525801] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26534; }","duration":"114.417928ms","start":"2026-06-10T15:47:52.456123Z","end":"2026-06-10T15:47:52.570541Z","steps":["trace[1346525801] 'read index received' (duration: 114.390687ms)","trace[1346525801] 'applied index is now lower than readState.Index' (duration: 25.791µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:47:52.570644Z","caller":"traceutil/trace.go:171","msg":"trace[526835729] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"251.524582ms","start":"2026-06-10T15:47:52.319093Z","end":"2026-06-10T15:47:52.570618Z","steps":["trace[526835729] 'process raft request' (duration: 251.323828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T15:47:52.570687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.541271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T15:47:52.570729Z","caller":"traceutil/trace.go:171","msg":"trace[136172244] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25685; }","duration":"114.631362ms","start":"2026-06-10T15:47:52.456081Z","end":"2026-06-10T15:47:52.570712Z","steps":["trace[136172244] 'agreement among raft nodes before linearized reading' (duration: 114.55363ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:52.636918Z","caller":"traceutil/trace.go:171","msg":"trace[1922163518] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"147.569814ms","start":"2026-06-10T15:47:52.489316Z","end":"2026-06-10T15:47:52.636886Z","steps":["trace[1922163518] 'process raft request' (duration: 147.460282ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:52.636924Z","caller":"traceutil/trace.go:171","msg":"trace[150588397] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"155.086625ms","start":"2026-06-10T15:47:52.481803Z","end":"2026-06-10T15:47:52.63689Z","steps":["trace[150588397] 'process raft request' (duration: 154.770628ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:52.637194Z","caller":"traceutil/trace.go:171","msg":"trace[2014502718] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"146.519353ms","start":"2026-06-10T15:47:52.49066Z","end":"2026-06-10T15:47:52.63718Z","steps":["trace[2014502718] 'process raft request' (duration: 146.37842ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:52.637223Z","caller":"traceutil/trace.go:171","msg":"trace[907096187] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"147.096654ms","start":"2026-06-10T15:47:52.490102Z","end":"2026-06-10T15:47:52.637198Z","steps":["trace[907096187] 'process raft request' (duration: 146.720307ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:47:52.637465Z","caller":"traceutil/trace.go:171","msg":"trace[134503474] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"145.640215ms","start":"2026-06-10T15:47:52.491787Z","end":"2026-06-10T15:47:52.637427Z","steps":["trace[134503474] 'process raft request' (duration: 145.350099ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T15:48:02.853021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22878} {"level":"info","ts":"2026-06-10T15:48:03.004023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22878,"took":"144.479531ms","hash":3706425959,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":36605952,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-10T15:48:03.004097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3706425959,"revision":22878,"compact-revision":20119} {"level":"info","ts":"2026-06-10T15:50:07.664822Z","caller":"traceutil/trace.go:171","msg":"trace[1530464045] transaction","detail":"{read_only:false; response_revision:27011; number_of_response:1; }","duration":"126.944969ms","start":"2026-06-10T15:50:07.537836Z","end":"2026-06-10T15:50:07.664781Z","steps":["trace[1530464045] 'process raft request' (duration: 99.845965ms)","trace[1530464045] 'compare' (duration: 26.88534ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T15:53:02.85903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25776} {"level":"info","ts":"2026-06-10T15:53:02.994656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25776,"took":"132.23263ms","hash":870228539,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":38633472,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-06-10T15:53:02.994713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":870228539,"revision":25776,"compact-revision":22878} {"level":"info","ts":"2026-06-10T15:53:59.997461Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T15:54:00.002942Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-10T15:54:00.00321Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-10T15:58:02.864923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28648} {"level":"info","ts":"2026-06-10T15:58:03.019745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28648,"took":"151.475396ms","hash":2379845611,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":31543296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T15:58:03.019854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2379845611,"revision":28648,"compact-revision":25776} {"level":"info","ts":"2026-06-10T16:03:02.87081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30868} {"level":"info","ts":"2026-06-10T16:03:02.983989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30868,"took":"110.091788ms","hash":2073990300,"current-db-size-bytes":60518400,"current-db-size":"60 MB","current-db-size-in-use-bytes":32276480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T16:03:02.984067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2073990300,"revision":30868,"compact-revision":28648} {"level":"info","ts":"2026-06-10T16:08:02.877031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33198} {"level":"info","ts":"2026-06-10T16:08:03.023771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33198,"took":"142.890739ms","hash":4288785554,"current-db-size-bytes":61562880,"current-db-size":"62 MB","current-db-size-in-use-bytes":32415744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T16:08:03.023845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4288785554,"revision":33198,"compact-revision":30868} {"level":"info","ts":"2026-06-10T16:13:02.881921Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35519} {"level":"info","ts":"2026-06-10T16:13:03.027771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35519,"took":"141.734461ms","hash":945764573,"current-db-size-bytes":62054400,"current-db-size":"62 MB","current-db-size-in-use-bytes":32849920,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-10T16:13:03.027835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":945764573,"revision":35519,"compact-revision":33198} {"level":"info","ts":"2026-06-10T16:15:13.802468Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T16:15:13.810719Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-10T16:15:13.810826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-10T16:18:02.88797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37753} {"level":"info","ts":"2026-06-10T16:18:03.001755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37753,"took":"111.112311ms","hash":2122118251,"current-db-size-bytes":63320064,"current-db-size":"63 MB","current-db-size-in-use-bytes":33636352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T16:18:03.001831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2122118251,"revision":37753,"compact-revision":35519} {"level":"info","ts":"2026-06-10T16:21:07.803311Z","caller":"traceutil/trace.go:171","msg":"trace[144346314] transaction","detail":"{read_only:false; response_revision:41421; number_of_response:1; }","duration":"305.405313ms","start":"2026-06-10T16:21:07.497884Z","end":"2026-06-10T16:21:07.803289Z","steps":["trace[144346314] 'process raft request' (duration: 305.176818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:07.803586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.497865Z","time spent":"305.614167ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T16:21:08.348818Z","caller":"traceutil/trace.go:171","msg":"trace[850424687] transaction","detail":"{read_only:false; response_revision:41422; number_of_response:1; }","duration":"849.610014ms","start":"2026-06-10T16:21:07.499181Z","end":"2026-06-10T16:21:08.348791Z","steps":["trace[850424687] 'process raft request' (duration: 848.907661ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.348859Z","caller":"traceutil/trace.go:171","msg":"trace[833154435] transaction","detail":"{read_only:false; response_revision:41423; number_of_response:1; }","duration":"846.596593ms","start":"2026-06-10T16:21:07.502242Z","end":"2026-06-10T16:21:08.348839Z","steps":["trace[833154435] 'process raft request' (duration: 846.462121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.348947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.499159Z","time spent":"849.721766ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.348949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.502229Z","time spent":"846.668015ms","remote":"127.0.0.1:33550","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-06-10T16:21:08.349309Z","caller":"traceutil/trace.go:171","msg":"trace[1367342951] transaction","detail":"{read_only:false; response_revision:41425; number_of_response:1; }","duration":"700.866624ms","start":"2026-06-10T16:21:07.648421Z","end":"2026-06-10T16:21:08.349287Z","steps":["trace[1367342951] 'process raft request' (duration: 700.560707ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.349326Z","caller":"traceutil/trace.go:171","msg":"trace[203492424] transaction","detail":"{read_only:false; response_revision:41424; number_of_response:1; }","duration":"703.021277ms","start":"2026-06-10T16:21:07.646277Z","end":"2026-06-10T16:21:08.349298Z","steps":["trace[203492424] 'process raft request' (duration: 702.525886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.349448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.648389Z","time spent":"700.982626ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.349529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.646252Z","time spent":"703.160219ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T16:21:08.350031Z","caller":"traceutil/trace.go:171","msg":"trace[1507666843] linearizableReadLoop","detail":"{readStateIndex:42712; appliedIndex:42709; }","duration":"835.652582ms","start":"2026-06-10T16:21:07.513441Z","end":"2026-06-10T16:21:08.349093Z","steps":["trace[1507666843] 'read index received' (duration: 289.633355ms)","trace[1507666843] 'applied index is now lower than readState.Index' (duration: 546.018007ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T16:21:08.351577Z","caller":"traceutil/trace.go:171","msg":"trace[215772161] transaction","detail":"{read_only:false; response_revision:41426; number_of_response:1; }","duration":"456.132884ms","start":"2026-06-10T16:21:07.895424Z","end":"2026-06-10T16:21:08.351557Z","steps":["trace[215772161] 'process raft request' (duration: 455.97869ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.351754Z","caller":"traceutil/trace.go:171","msg":"trace[1622093882] transaction","detail":"{read_only:false; response_revision:41427; number_of_response:1; }","duration":"454.542942ms","start":"2026-06-10T16:21:07.897203Z","end":"2026-06-10T16:21:08.351746Z","steps":["trace[1622093882] 'process raft request' (duration: 454.312148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.351802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.897172Z","time spent":"454.606904ms","remote":"127.0.0.1:33550","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-06-10T16:21:08.351932Z","caller":"traceutil/trace.go:171","msg":"trace[1953894772] transaction","detail":"{read_only:false; response_revision:41428; number_of_response:1; }","duration":"454.688465ms","start":"2026-06-10T16:21:07.897236Z","end":"2026-06-10T16:21:08.351925Z","steps":["trace[1953894772] 'process raft request' (duration: 454.477811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.35197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.897217Z","time spent":"454.733736ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.352119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.11827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:08.352155Z","caller":"traceutil/trace.go:171","msg":"trace[237902848] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:41429; }","duration":"204.19326ms","start":"2026-06-10T16:21:08.147954Z","end":"2026-06-10T16:21:08.352147Z","steps":["trace[237902848] 'agreement among raft nodes before linearized reading' (duration: 204.137339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.352168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.252279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-10T16:21:08.352174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.566496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"info","ts":"2026-06-10T16:21:08.352222Z","caller":"traceutil/trace.go:171","msg":"trace[508665194] transaction","detail":"{read_only:false; response_revision:41429; number_of_response:1; }","duration":"318.278683ms","start":"2026-06-10T16:21:08.033936Z","end":"2026-06-10T16:21:08.352215Z","steps":["trace[508665194] 'process raft request' (duration: 317.963945ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:08.352235Z","caller":"traceutil/trace.go:171","msg":"trace[2136457542] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:41429; }","duration":"800.649827ms","start":"2026-06-10T16:21:07.551567Z","end":"2026-06-10T16:21:08.352217Z","steps":["trace[2136457542] 'agreement among raft nodes before linearized reading' (duration: 800.497104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.352264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.033903Z","time spent":"318.335904ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.352273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.551551Z","time spent":"800.714918ms","remote":"127.0.0.1:33500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1137,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" "} {"level":"info","ts":"2026-06-10T16:21:08.352198Z","caller":"traceutil/trace.go:171","msg":"trace[80537230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:41429; }","duration":"128.283839ms","start":"2026-06-10T16:21:08.223905Z","end":"2026-06-10T16:21:08.352189Z","steps":["trace[80537230] 'agreement among raft nodes before linearized reading' (duration: 128.177407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.352374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.263178ms","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-06-10T16:21:08.352404Z","caller":"traceutil/trace.go:171","msg":"trace[1443760196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:41429; }","duration":"233.333669ms","start":"2026-06-10T16:21:08.119063Z","end":"2026-06-10T16:21:08.352396Z","steps":["trace[1443760196] 'agreement among raft nodes before linearized reading' (duration: 233.263078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.351686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.895397Z","time spent":"456.230647ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:08.353844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.387688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:390085"} {"level":"info","ts":"2026-06-10T16:21:08.353913Z","caller":"traceutil/trace.go:171","msg":"trace[1290315992] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:41425; }","duration":"840.503311ms","start":"2026-06-10T16:21:07.513394Z","end":"2026-06-10T16:21:08.353897Z","steps":["trace[1290315992] 'agreement among raft nodes before linearized reading' (duration: 836.081712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:08.354011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:07.513372Z","time spent":"840.609613ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1666,"response size":390107,"request content":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 "} {"level":"warn","ts":"2026-06-10T16:21:10.472346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007799795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.472453Z","caller":"traceutil/trace.go:171","msg":"trace[1346906110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41431; }","duration":"2.007948088s","start":"2026-06-10T16:21:08.464479Z","end":"2026-06-10T16:21:10.472427Z","steps":["trace[1346906110] 'range keys from in-memory index tree' (duration: 2.007712173s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.472503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00406255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-neutron-server\" ","response":"range_response_count:1 size:710"} {"level":"info","ts":"2026-06-10T16:21:10.472786Z","caller":"traceutil/trace.go:171","msg":"trace[1466462976] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-neutron-server; range_end:; response_count:1; response_revision:41431; }","duration":"2.004357727s","start":"2026-06-10T16:21:08.4684Z","end":"2026-06-10T16:21:10.472757Z","steps":["trace[1466462976] 'range keys from in-memory index tree' (duration: 2.003924908s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.472851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.468393Z","time spent":"2.004443119s","remote":"127.0.0.1:33500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":732,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-neutron-server\" "} {"level":"warn","ts":"2026-06-10T16:21:10.472528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.464464Z","time spent":"2.00804834s","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T16:21:10.47327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206732712s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-10T16:21:10.473479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800018784s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T16:21:10.473639Z","caller":"traceutil/trace.go:171","msg":"trace[1721933604] transaction","detail":"{read_only:false; response_revision:41433; number_of_response:1; }","duration":"1.207195801s","start":"2026-06-10T16:21:09.266428Z","end":"2026-06-10T16:21:10.473624Z","steps":["trace[1721933604] 'process raft request' (duration: 1.20713895s)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.473668Z","caller":"traceutil/trace.go:171","msg":"trace[22664490] transaction","detail":"{read_only:false; response_revision:41432; number_of_response:1; }","duration":"1.990015328s","start":"2026-06-10T16:21:08.4836Z","end":"2026-06-10T16:21:10.473615Z","steps":["trace[22664490] 'process raft request' (duration: 189.79183ms)","trace[22664490] 'compare' (duration: 1.799894141s)"],"step_count":2} {"level":"warn","ts":"2026-06-10T16:21:10.473711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.266404Z","time spent":"1.207275232s","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T16:21:10.473776Z","caller":"traceutil/trace.go:171","msg":"trace[570729137] linearizableReadLoop","detail":"{readStateIndex:42721; appliedIndex:42720; }","duration":"1.981277171s","start":"2026-06-10T16:21:08.49248Z","end":"2026-06-10T16:21:10.473757Z","steps":["trace[570729137] 'read index received' (duration: 180.858029ms)","trace[570729137] 'applied index is now lower than readState.Index' (duration: 1.800417361s)"],"step_count":2} {"level":"warn","ts":"2026-06-10T16:21:10.473778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.483578Z","time spent":"1.990132041s","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T16:21:10.473914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981408353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.473939Z","caller":"traceutil/trace.go:171","msg":"trace[1599004847] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:41433; }","duration":"1.981484335s","start":"2026-06-10T16:21:08.492448Z","end":"2026-06-10T16:21:10.473933Z","steps":["trace[1599004847] 'agreement among raft nodes before linearized reading' (duration: 1.981407513s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.473961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.492438Z","time spent":"1.981517665s","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"info","ts":"2026-06-10T16:21:10.479371Z","caller":"traceutil/trace.go:171","msg":"trace[1298345300] transaction","detail":"{read_only:false; response_revision:41434; number_of_response:1; }","duration":"125.522123ms","start":"2026-06-10T16:21:10.353815Z","end":"2026-06-10T16:21:10.479337Z","steps":["trace[1298345300] 'process raft request' (duration: 125.328129ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479704Z","caller":"traceutil/trace.go:171","msg":"trace[589264898] transaction","detail":"{read_only:false; response_revision:41435; number_of_response:1; }","duration":"125.82973ms","start":"2026-06-10T16:21:10.353843Z","end":"2026-06-10T16:21:10.479672Z","steps":["trace[589264898] 'process raft request' (duration: 125.447262ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479735Z","caller":"traceutil/trace.go:171","msg":"trace[852621740] transaction","detail":"{read_only:false; response_revision:41437; number_of_response:1; }","duration":"121.221466ms","start":"2026-06-10T16:21:10.358496Z","end":"2026-06-10T16:21:10.479717Z","steps":["trace[852621740] 'process raft request' (duration: 121.121764ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.479901Z","caller":"traceutil/trace.go:171","msg":"trace[1313893413] transaction","detail":"{read_only:false; response_revision:41436; number_of_response:1; }","duration":"125.293759ms","start":"2026-06-10T16:21:10.354593Z","end":"2026-06-10T16:21:10.479887Z","steps":["trace[1313893413] 'process raft request' (duration: 124.923652ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.480115Z","caller":"traceutil/trace.go:171","msg":"trace[1731522727] transaction","detail":"{read_only:false; response_revision:41438; number_of_response:1; }","duration":"120.311248ms","start":"2026-06-10T16:21:10.359789Z","end":"2026-06-10T16:21:10.480101Z","steps":["trace[1731522727] 'process raft request' (duration: 119.88981ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.480313Z","caller":"traceutil/trace.go:171","msg":"trace[1216377322] transaction","detail":"{read_only:false; response_revision:41439; number_of_response:1; }","duration":"120.463301ms","start":"2026-06-10T16:21:10.359838Z","end":"2026-06-10T16:21:10.480301Z","steps":["trace[1216377322] 'process raft request' (duration: 120.042663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.584386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T16:21:10.480778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582164206s","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-06-10T16:21:10.48079Z","caller":"traceutil/trace.go:171","msg":"trace[847226862] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:41440; }","duration":"134.710679ms","start":"2026-06-10T16:21:10.346057Z","end":"2026-06-10T16:21:10.480767Z","steps":["trace[847226862] 'agreement among raft nodes before linearized reading' (duration: 134.558846ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T16:21:10.480835Z","caller":"traceutil/trace.go:171","msg":"trace[161335735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:41440; }","duration":"1.582252718s","start":"2026-06-10T16:21:08.898553Z","end":"2026-06-10T16:21:10.480806Z","steps":["trace[161335735] 'agreement among raft nodes before linearized reading' (duration: 1.582096375s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.480875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.898532Z","time spent":"1.58233341s","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-10T16:21:10.48099Z","caller":"traceutil/trace.go:171","msg":"trace[742282019] transaction","detail":"{read_only:false; response_revision:41440; number_of_response:1; }","duration":"120.948781ms","start":"2026-06-10T16:21:10.36003Z","end":"2026-06-10T16:21:10.480979Z","steps":["trace[742282019] 'process raft request' (duration: 120.036963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.163805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.481065Z","caller":"traceutil/trace.go:171","msg":"trace[836233950] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:41440; }","duration":"684.245887ms","start":"2026-06-10T16:21:09.796807Z","end":"2026-06-10T16:21:10.481053Z","steps":["trace[836233950] 'agreement among raft nodes before linearized reading' (duration: 684.164076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433285234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-10T16:21:10.481101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.796788Z","time spent":"684.307029ms","remote":"127.0.0.1:46110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"info","ts":"2026-06-10T16:21:10.481112Z","caller":"traceutil/trace.go:171","msg":"trace[1730812644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:41440; }","duration":"1.433353414s","start":"2026-06-10T16:21:09.04775Z","end":"2026-06-10T16:21:10.481103Z","steps":["trace[1730812644] 'agreement among raft nodes before linearized reading' (duration: 1.433264472s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.04773Z","time spent":"1.433402226s","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T16:21:10.481164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.959611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-10T16:21:10.481237Z","caller":"traceutil/trace.go:171","msg":"trace[1189555599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:41440; }","duration":"674.096514ms","start":"2026-06-10T16:21:09.807128Z","end":"2026-06-10T16:21:10.481225Z","steps":["trace[1189555599] 'agreement among raft nodes before linearized reading' (duration: 673.871978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.4813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:09.807107Z","time spent":"674.182645ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T16:21:10.481408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.707266ms","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-06-10T16:21:10.481465Z","caller":"traceutil/trace.go:171","msg":"trace[194877507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:41440; }","duration":"118.798488ms","start":"2026-06-10T16:21:10.362655Z","end":"2026-06-10T16:21:10.481454Z","steps":["trace[194877507] 'agreement among raft nodes before linearized reading' (duration: 118.658856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.48148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.991612ms","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-06-10T16:21:10.481509Z","caller":"traceutil/trace.go:171","msg":"trace[1372901904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:41440; }","duration":"120.063413ms","start":"2026-06-10T16:21:10.361439Z","end":"2026-06-10T16:21:10.481502Z","steps":["trace[1372901904] 'agreement among raft nodes before linearized reading' (duration: 119.953731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.4816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.618707ms","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-06-10T16:21:10.48162Z","caller":"traceutil/trace.go:171","msg":"trace[102153023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:41440; }","duration":"129.677758ms","start":"2026-06-10T16:21:10.351938Z","end":"2026-06-10T16:21:10.481616Z","steps":["trace[102153023] 'agreement among raft nodes before linearized reading' (duration: 129.620686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815449605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T16:21:10.481724Z","caller":"traceutil/trace.go:171","msg":"trace[1877030725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41440; }","duration":"1.815529586s","start":"2026-06-10T16:21:08.666186Z","end":"2026-06-10T16:21:10.481715Z","steps":["trace[1877030725] 'agreement among raft nodes before linearized reading' (duration: 1.815468595s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.666164Z","time spent":"1.815582107s","remote":"127.0.0.1:33306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T16:21:10.481691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.651228612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T16:21:10.481866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839719654s","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":"info","ts":"2026-06-10T16:21:10.481894Z","caller":"traceutil/trace.go:171","msg":"trace[465705117] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:41440; }","duration":"1.839784576s","start":"2026-06-10T16:21:08.642104Z","end":"2026-06-10T16:21:10.481888Z","steps":["trace[465705117] 'agreement among raft nodes before linearized reading' (duration: 1.839738065s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.481916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.642083Z","time spent":"1.839828127s","remote":"127.0.0.1:60882","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":"info","ts":"2026-06-10T16:21:10.482005Z","caller":"traceutil/trace.go:171","msg":"trace[1303774593] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:41440; }","duration":"1.651391005s","start":"2026-06-10T16:21:08.830456Z","end":"2026-06-10T16:21:10.481847Z","steps":["trace[1303774593] 'agreement among raft nodes before linearized reading' (duration: 1.651213772s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.482069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.830392Z","time spent":"1.6516606s","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-10T16:21:10.485929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924108397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:390085"} {"level":"info","ts":"2026-06-10T16:21:10.486005Z","caller":"traceutil/trace.go:171","msg":"trace[2018587324] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:41440; }","duration":"1.92424386s","start":"2026-06-10T16:21:08.561743Z","end":"2026-06-10T16:21:10.485986Z","steps":["trace[2018587324] 'agreement among raft nodes before linearized reading' (duration: 1.918755569s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T16:21:10.486058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T16:21:08.561708Z","time spent":"1.924326922s","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1666,"response size":390107,"request content":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 "}