{"level":"warn","ts":"2026-06-16T10:26:15.284437Z","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-16T10:26:15.284528Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-16T10:26:15.284597Z","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-16T10:26:15.284605Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-16T10:26:15.284622Z","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-16T10:26:15.285333Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-16T10:26:15.285409Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-16T10:26:15.288623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.991757ms"} {"level":"info","ts":"2026-06-16T10:26:15.293171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-16T10:26:15.293302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T10:26:15.293335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-16T10:26:15.29335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T10:26:15.29337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-16T10:26:15.293421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-16T10:26:15.297723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T10:26:15.299144Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T10:26:15.300235Z","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-16T10:26:15.302885Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T10:26:15.303064Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T10:26:15.303193Z","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-16T10:26:15.303326Z","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-16T10:26:15.303344Z","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-16T10:26:15.304747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-16T10:26:15.304942Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-16T10:26:15.307788Z","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-16T10:26:15.308001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T10:26:15.308031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T10:26:15.308534Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-16T10:26:15.308568Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-16T10:26:15.394119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T10:26:15.39417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T10:26:15.394213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-16T10:26:15.39423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-16T10:26:15.394238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-16T10:26:15.394249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-16T10:26:15.394259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-16T10:26:15.395211Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T10:26:15.395247Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:15.395441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:26:15.395426Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:26:15.395629Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T10:26:15.395689Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T10:26:15.398751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T10:26:15.399945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-16T10:26:15.407674Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:15.40772Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:15.407735Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-16T10:26:54.394485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.866984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:26:54.394763Z","caller":"traceutil/trace.go:171","msg":"trace[132193363] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:528; }","duration":"231.19452ms","start":"2026-06-16T10:26:54.163528Z","end":"2026-06-16T10:26:54.394723Z","steps":["trace[132193363] 'range keys from in-memory index tree' (duration: 230.804043ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:55.286123Z","caller":"traceutil/trace.go:171","msg":"trace[1604607273] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"122.166976ms","start":"2026-06-16T10:26:55.163907Z","end":"2026-06-16T10:26:55.286074Z","steps":["trace[1604607273] 'process raft request' (duration: 69.110141ms)","trace[1604607273] 'compare' (duration: 52.83498ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:55.289721Z","caller":"traceutil/trace.go:171","msg":"trace[447682316] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"104.492299ms","start":"2026-06-16T10:26:55.185163Z","end":"2026-06-16T10:26:55.289656Z","steps":["trace[447682316] 'process raft request' (duration: 104.141203ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:55.290147Z","caller":"traceutil/trace.go:171","msg":"trace[166326358] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"100.94183ms","start":"2026-06-16T10:26:55.189182Z","end":"2026-06-16T10:26:55.290123Z","steps":["trace[166326358] 'process raft request' (duration: 100.39562ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:01.212378Z","caller":"traceutil/trace.go:171","msg":"trace[2002339692] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"206.922016ms","start":"2026-06-16T10:27:01.005413Z","end":"2026-06-16T10:27:01.212335Z","steps":["trace[2002339692] 'process raft request' (duration: 191.536753ms)","trace[2002339692] 'compare' (duration: 14.818213ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:27:01.699021Z","caller":"traceutil/trace.go:171","msg":"trace[323694161] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"389.774333ms","start":"2026-06-16T10:27:01.309212Z","end":"2026-06-16T10:27:01.698986Z","steps":["trace[323694161] 'read index received' (duration: 379.454215ms)","trace[323694161] 'applied index is now lower than readState.Index' (duration: 10.318788ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:27:01.699058Z","caller":"traceutil/trace.go:171","msg":"trace[414637998] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"415.827001ms","start":"2026-06-16T10:27:01.28318Z","end":"2026-06-16T10:27:01.699007Z","steps":["trace[414637998] 'process raft request' (duration: 405.381571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:01.699415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.203371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T10:27:01.6995Z","caller":"traceutil/trace.go:171","msg":"trace[1296712579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"390.339313ms","start":"2026-06-16T10:27:01.309138Z","end":"2026-06-16T10:27:01.699478Z","steps":["trace[1296712579] 'agreement among raft nodes before linearized reading' (duration: 389.963836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:01.699547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:01.309114Z","time spent":"390.420005ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T10:27:01.699772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:01.28314Z","time spent":"416.020165ms","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T10:27:16.223969Z","caller":"traceutil/trace.go:171","msg":"trace[1934307225] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"128.760732ms","start":"2026-06-16T10:27:16.095174Z","end":"2026-06-16T10:27:16.223934Z","steps":["trace[1934307225] 'read index received' (duration: 128.587139ms)","trace[1934307225] 'applied index is now lower than readState.Index' (duration: 172.163µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:27:16.22411Z","caller":"traceutil/trace.go:171","msg":"trace[1031272334] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"131.534755ms","start":"2026-06-16T10:27:16.092511Z","end":"2026-06-16T10:27:16.224046Z","steps":["trace[1031272334] 'process raft request' (duration: 131.23481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:16.224267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.081568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T10:27:16.224327Z","caller":"traceutil/trace.go:171","msg":"trace[841605018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"129.18791ms","start":"2026-06-16T10:27:16.095125Z","end":"2026-06-16T10:27:16.224313Z","steps":["trace[841605018] 'agreement among raft nodes before linearized reading' (duration: 128.992537ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:16.361489Z","caller":"traceutil/trace.go:171","msg":"trace[32884968] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"132.022905ms","start":"2026-06-16T10:27:16.229437Z","end":"2026-06-16T10:27:16.36146Z","steps":["trace[32884968] 'process raft request' (duration: 131.145217ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:16.362752Z","caller":"traceutil/trace.go:171","msg":"trace[379106482] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"133.298629ms","start":"2026-06-16T10:27:16.229429Z","end":"2026-06-16T10:27:16.362727Z","steps":["trace[379106482] 'process raft request' (duration: 133.107915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:35.866606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.760518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T10:28:35.86672Z","caller":"traceutil/trace.go:171","msg":"trace[18509885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"196.922782ms","start":"2026-06-16T10:28:35.669772Z","end":"2026-06-16T10:28:35.866694Z","steps":["trace[18509885] 'range keys from in-memory index tree' (duration: 196.582925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:36.410504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.489743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T10:28:36.410644Z","caller":"traceutil/trace.go:171","msg":"trace[90545069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1887; }","duration":"130.650706ms","start":"2026-06-16T10:28:36.279962Z","end":"2026-06-16T10:28:36.410613Z","steps":["trace[90545069] 'range keys from in-memory index tree' (duration: 130.228267ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:25.210302Z","caller":"traceutil/trace.go:171","msg":"trace[1796351825] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"114.696965ms","start":"2026-06-16T10:29:25.095584Z","end":"2026-06-16T10:29:25.210281Z","steps":["trace[1796351825] 'process raft request' (duration: 93.845677ms)","trace[1796351825] 'compare' (duration: 20.41399ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:25.210286Z","caller":"traceutil/trace.go:171","msg":"trace[1603797023] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"103.925219ms","start":"2026-06-16T10:29:25.106316Z","end":"2026-06-16T10:29:25.210241Z","steps":["trace[1603797023] 'read index received' (duration: 83.142223ms)","trace[1603797023] 'applied index is now lower than readState.Index' (duration: 20.781756ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:29:25.210481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.131733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T10:29:25.210562Z","caller":"traceutil/trace.go:171","msg":"trace[2039440037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2206; }","duration":"104.242566ms","start":"2026-06-16T10:29:25.1063Z","end":"2026-06-16T10:29:25.210542Z","steps":["trace[2039440037] 'agreement among raft nodes before linearized reading' (duration: 104.045542ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:31.200157Z","caller":"traceutil/trace.go:171","msg":"trace[559095428] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"131.686988ms","start":"2026-06-16T10:29:31.068441Z","end":"2026-06-16T10:29:31.200128Z","steps":["trace[559095428] 'process raft request' (duration: 131.520095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.470325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.785022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:29:31.470443Z","caller":"traceutil/trace.go:171","msg":"trace[252220065] linearizableReadLoop","detail":"{readStateIndex:2324; appliedIndex:2322; }","duration":"357.53337ms","start":"2026-06-16T10:29:31.11289Z","end":"2026-06-16T10:29:31.470424Z","steps":["trace[252220065] 'read index received' (duration: 87.089289ms)","trace[252220065] 'applied index is now lower than readState.Index' (duration: 270.442721ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:31.470552Z","caller":"traceutil/trace.go:171","msg":"trace[2029980877] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"370.09081ms","start":"2026-06-16T10:29:31.100421Z","end":"2026-06-16T10:29:31.470512Z","steps":["trace[2029980877] 'process raft request' (duration: 239.721346ms)","trace[2029980877] 'compare' (duration: 129.68433ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:29:31.470719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:31.100389Z","time spent":"370.244623ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:29:31.470752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.841927ms","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-16T10:29:31.470829Z","caller":"traceutil/trace.go:171","msg":"trace[972552756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2234; }","duration":"357.963268ms","start":"2026-06-16T10:29:31.112846Z","end":"2026-06-16T10:29:31.470809Z","steps":["trace[972552756] 'agreement among raft nodes before linearized reading' (duration: 357.741594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.470872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:31.112828Z","time spent":"358.0351ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T10:29:31.500675Z","caller":"traceutil/trace.go:171","msg":"trace[62084149] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"117.728372ms","start":"2026-06-16T10:29:31.382923Z","end":"2026-06-16T10:29:31.500651Z","steps":["trace[62084149] 'process raft request' (duration: 117.241573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.500873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.074998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-16T10:29:31.500825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.457598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T10:29:31.500986Z","caller":"traceutil/trace.go:171","msg":"trace[769893960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"266.230911ms","start":"2026-06-16T10:29:31.234738Z","end":"2026-06-16T10:29:31.500969Z","steps":["trace[769893960] 'agreement among raft nodes before linearized reading' (duration: 265.969526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.500943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.027634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T10:29:31.500916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.642612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:29:31.501063Z","caller":"traceutil/trace.go:171","msg":"trace[1006175197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2235; }","duration":"252.703544ms","start":"2026-06-16T10:29:31.248297Z","end":"2026-06-16T10:29:31.501Z","steps":["trace[1006175197] 'agreement among raft nodes before linearized reading' (duration: 252.281856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.501038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.287044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T10:29:31.501212Z","caller":"traceutil/trace.go:171","msg":"trace[174620742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2235; }","duration":"211.530939ms","start":"2026-06-16T10:29:31.289648Z","end":"2026-06-16T10:29:31.501179Z","steps":["trace[174620742] 'agreement among raft nodes before linearized reading' (duration: 211.05503ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:31.50108Z","caller":"traceutil/trace.go:171","msg":"trace[1264827691] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2235; }","duration":"342.28213ms","start":"2026-06-16T10:29:31.158768Z","end":"2026-06-16T10:29:31.50105Z","steps":["trace[1264827691] 'agreement among raft nodes before linearized reading' (duration: 341.947253ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:31.501263Z","caller":"traceutil/trace.go:171","msg":"trace[970810330] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2235; }","duration":"105.989019ms","start":"2026-06-16T10:29:31.395168Z","end":"2026-06-16T10:29:31.501157Z","steps":["trace[970810330] 'agreement among raft nodes before linearized reading' (duration: 105.438498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.50138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:31.158748Z","time spent":"342.584356ms","remote":"127.0.0.1:39816","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-16T10:29:31.632015Z","caller":"traceutil/trace.go:171","msg":"trace[3677216] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"157.406088ms","start":"2026-06-16T10:29:31.474566Z","end":"2026-06-16T10:29:31.631972Z","steps":["trace[3677216] 'process raft request' (duration: 123.35842ms)","trace[3677216] 'compare' (duration: 33.749143ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:31.632123Z","caller":"traceutil/trace.go:171","msg":"trace[1437940356] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2325; }","duration":"131.714918ms","start":"2026-06-16T10:29:31.500361Z","end":"2026-06-16T10:29:31.632076Z","steps":["trace[1437940356] 'read index received' (duration: 97.67601ms)","trace[1437940356] 'applied index is now lower than readState.Index' (duration: 34.006938ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:31.632169Z","caller":"traceutil/trace.go:171","msg":"trace[1686128445] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"125.588923ms","start":"2026-06-16T10:29:31.506556Z","end":"2026-06-16T10:29:31.632145Z","steps":["trace[1686128445] 'process raft request' (duration: 125.507761ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:31.632175Z","caller":"traceutil/trace.go:171","msg":"trace[2015514430] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"126.241055ms","start":"2026-06-16T10:29:31.505902Z","end":"2026-06-16T10:29:31.632143Z","steps":["trace[2015514430] 'process raft request' (duration: 126.044601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:31.63254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.539344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:29:31.632608Z","caller":"traceutil/trace.go:171","msg":"trace[1777385533] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2238; }","duration":"132.654196ms","start":"2026-06-16T10:29:31.499934Z","end":"2026-06-16T10:29:31.632589Z","steps":["trace[1777385533] 'agreement among raft nodes before linearized reading' (duration: 132.269769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:30:40.418005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.763375ms","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-16T10:30:40.418115Z","caller":"traceutil/trace.go:171","msg":"trace[108700298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2807; }","duration":"100.926099ms","start":"2026-06-16T10:30:40.317168Z","end":"2026-06-16T10:30:40.418095Z","steps":["trace[108700298] 'range keys from in-memory index tree' (duration: 100.515541ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:30:45.997219Z","caller":"traceutil/trace.go:171","msg":"trace[1188208531] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"171.483286ms","start":"2026-06-16T10:30:45.825698Z","end":"2026-06-16T10:30:45.997181Z","steps":["trace[1188208531] 'process raft request' (duration: 76.501871ms)","trace[1188208531] 'compare' (duration: 94.736651ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:34:51.500568Z","caller":"traceutil/trace.go:171","msg":"trace[423486083] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"119.586252ms","start":"2026-06-16T10:34:51.380947Z","end":"2026-06-16T10:34:51.500533Z","steps":["trace[423486083] 'process raft request' (duration: 80.794969ms)","trace[423486083] 'compare' (duration: 38.587799ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:34:56.648805Z","caller":"traceutil/trace.go:171","msg":"trace[1215868759] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"126.471585ms","start":"2026-06-16T10:34:56.522281Z","end":"2026-06-16T10:34:56.648752Z","steps":["trace[1215868759] 'process raft request' (duration: 76.072239ms)","trace[1215868759] 'compare' (duration: 50.09591ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:35:01.757065Z","caller":"traceutil/trace.go:171","msg":"trace[846949557] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"108.201235ms","start":"2026-06-16T10:35:01.648833Z","end":"2026-06-16T10:35:01.757034Z","steps":["trace[846949557] 'process raft request' (duration: 108.038912ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:01.757182Z","caller":"traceutil/trace.go:171","msg":"trace[2094398882] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5319; }","duration":"108.197574ms","start":"2026-06-16T10:35:01.648945Z","end":"2026-06-16T10:35:01.757142Z","steps":["trace[2094398882] 'read index received' (duration: 108.181244ms)","trace[2094398882] 'applied index is now lower than readState.Index' (duration: 13.06µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:35:01.757591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.594763ms","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-16T10:35:01.757711Z","caller":"traceutil/trace.go:171","msg":"trace[21654546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5122; }","duration":"108.753065ms","start":"2026-06-16T10:35:01.648934Z","end":"2026-06-16T10:35:01.757688Z","steps":["trace[21654546] 'agreement among raft nodes before linearized reading' (duration: 108.378678ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:01.887015Z","caller":"traceutil/trace.go:171","msg":"trace[1552992095] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"124.251593ms","start":"2026-06-16T10:35:01.762738Z","end":"2026-06-16T10:35:01.88699Z","steps":["trace[1552992095] 'process raft request' (duration: 124.202542ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:01.887138Z","caller":"traceutil/trace.go:171","msg":"trace[1270673423] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"236.918103ms","start":"2026-06-16T10:35:01.650206Z","end":"2026-06-16T10:35:01.887125Z","steps":["trace[1270673423] 'process raft request' (duration: 155.045414ms)","trace[1270673423] 'compare' (duration: 81.553823ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:35:01.887226Z","caller":"traceutil/trace.go:171","msg":"trace[1396776258] linearizableReadLoop","detail":"{readStateIndex:5320; appliedIndex:5319; }","duration":"129.936911ms","start":"2026-06-16T10:35:01.757278Z","end":"2026-06-16T10:35:01.887215Z","steps":["trace[1396776258] 'read index received' (duration: 48.01481ms)","trace[1396776258] 'applied index is now lower than readState.Index' (duration: 81.921071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:35:01.887403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.702395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:35:01.887455Z","caller":"traceutil/trace.go:171","msg":"trace[1109595889] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5124; }","duration":"155.796196ms","start":"2026-06-16T10:35:01.731646Z","end":"2026-06-16T10:35:01.887442Z","steps":["trace[1109595889] 'agreement among raft nodes before linearized reading' (duration: 155.647763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:35:01.887628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.572967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-917f7396-4996-48e3-8ffc-11788e140860\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-06-16T10:35:01.887657Z","caller":"traceutil/trace.go:171","msg":"trace[610876273] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-917f7396-4996-48e3-8ffc-11788e140860; range_end:; response_count:1; response_revision:5124; }","duration":"161.634478ms","start":"2026-06-16T10:35:01.726013Z","end":"2026-06-16T10:35:01.887647Z","steps":["trace[610876273] 'agreement among raft nodes before linearized reading' (duration: 161.557197ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:14.658204Z","caller":"traceutil/trace.go:171","msg":"trace[2056797780] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"103.513505ms","start":"2026-06-16T10:35:14.554655Z","end":"2026-06-16T10:35:14.658168Z","steps":["trace[2056797780] 'process raft request' (duration: 86.737904ms)","trace[2056797780] 'compare' (duration: 16.342243ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:35:14.8165Z","caller":"traceutil/trace.go:171","msg":"trace[1171144766] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"101.526296ms","start":"2026-06-16T10:35:14.714935Z","end":"2026-06-16T10:35:14.816462Z","steps":["trace[1171144766] 'process raft request' (duration: 57.174026ms)","trace[1171144766] 'compare' (duration: 44.067994ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:35:14.816545Z","caller":"traceutil/trace.go:171","msg":"trace[173457038] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"101.1733ms","start":"2026-06-16T10:35:14.715339Z","end":"2026-06-16T10:35:14.816513Z","steps":["trace[173457038] 'process raft request' (duration: 101.003017ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.164831Z","caller":"traceutil/trace.go:171","msg":"trace[1400010164] linearizableReadLoop","detail":"{readStateIndex:5577; appliedIndex:5572; }","duration":"243.53899ms","start":"2026-06-16T10:35:14.921272Z","end":"2026-06-16T10:35:15.164811Z","steps":["trace[1400010164] 'read index received' (duration: 162.110678ms)","trace[1400010164] 'applied index is now lower than readState.Index' (duration: 81.427622ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:35:15.165002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.727613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:35:15.165052Z","caller":"traceutil/trace.go:171","msg":"trace[1356457259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5374; }","duration":"243.851946ms","start":"2026-06-16T10:35:14.921188Z","end":"2026-06-16T10:35:15.16504Z","steps":["trace[1356457259] 'agreement among raft nodes before linearized reading' (duration: 243.707613ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.165062Z","caller":"traceutil/trace.go:171","msg":"trace[1387150866] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"263.677565ms","start":"2026-06-16T10:35:14.90137Z","end":"2026-06-16T10:35:15.165048Z","steps":["trace[1387150866] 'process raft request' (duration: 263.314608ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.165239Z","caller":"traceutil/trace.go:171","msg":"trace[705057679] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"263.235508ms","start":"2026-06-16T10:35:14.901993Z","end":"2026-06-16T10:35:15.165229Z","steps":["trace[705057679] 'process raft request' (duration: 262.718658ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.165499Z","caller":"traceutil/trace.go:171","msg":"trace[1668851783] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"252.642103ms","start":"2026-06-16T10:35:14.912844Z","end":"2026-06-16T10:35:15.165486Z","steps":["trace[1668851783] 'process raft request' (duration: 251.895529ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.165567Z","caller":"traceutil/trace.go:171","msg":"trace[202592672] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"264.874249ms","start":"2026-06-16T10:35:14.900654Z","end":"2026-06-16T10:35:15.165529Z","steps":["trace[202592672] 'process raft request' (duration: 182.804616ms)","trace[202592672] 'compare' (duration: 80.723648ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:35:15.165576Z","caller":"traceutil/trace.go:171","msg":"trace[1482852423] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"264.282377ms","start":"2026-06-16T10:35:14.901235Z","end":"2026-06-16T10:35:15.165517Z","steps":["trace[1482852423] 'process raft request' (duration: 263.39588ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:15.165737Z","caller":"traceutil/trace.go:171","msg":"trace[1515339051] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"125.940344ms","start":"2026-06-16T10:35:15.039753Z","end":"2026-06-16T10:35:15.165693Z","steps":["trace[1515339051] 'process raft request' (duration: 125.027027ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:35:23.634232Z","caller":"traceutil/trace.go:171","msg":"trace[737864838] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"104.759556ms","start":"2026-06-16T10:35:23.529433Z","end":"2026-06-16T10:35:23.634193Z","steps":["trace[737864838] 'process raft request' (duration: 104.529851ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:36:15.947813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-06-16T10:36:16.08128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"131.085041ms","hash":3913979491,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":33701888,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T10:36:16.081356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3913979491,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-06-16T10:41:15.979194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6045} {"level":"info","ts":"2026-06-16T10:41:16.120444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6045,"took":"138.342512ms","hash":1300009207,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":21733376,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-16T10:41:16.120564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1300009207,"revision":6045,"compact-revision":3000} {"level":"info","ts":"2026-06-16T10:41:54.315286Z","caller":"traceutil/trace.go:171","msg":"trace[1917165871] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8792; }","duration":"141.733281ms","start":"2026-06-16T10:41:54.173511Z","end":"2026-06-16T10:41:54.315248Z","steps":["trace[1917165871] 'read index received' (duration: 123.920956ms)","trace[1917165871] 'applied index is now lower than readState.Index' (duration: 17.810925ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:41:54.315384Z","caller":"traceutil/trace.go:171","msg":"trace[922417289] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"189.344783ms","start":"2026-06-16T10:41:54.125994Z","end":"2026-06-16T10:41:54.315339Z","steps":["trace[922417289] 'process raft request' (duration: 171.521388ms)","trace[922417289] 'compare' (duration: 17.56671ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:41:54.315569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.037547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-16T10:41:54.315613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.043965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T10:41:54.315628Z","caller":"traceutil/trace.go:171","msg":"trace[513021988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8490; }","duration":"142.17779ms","start":"2026-06-16T10:41:54.173435Z","end":"2026-06-16T10:41:54.315613Z","steps":["trace[513021988] 'agreement among raft nodes before linearized reading' (duration: 141.896004ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:41:54.31565Z","caller":"traceutil/trace.go:171","msg":"trace[1492416295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8490; }","duration":"131.148747ms","start":"2026-06-16T10:41:54.184492Z","end":"2026-06-16T10:41:54.315641Z","steps":["trace[1492416295] 'agreement among raft nodes before linearized reading' (duration: 130.962474ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:41:54.827516Z","caller":"traceutil/trace.go:171","msg":"trace[729828244] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"133.556443ms","start":"2026-06-16T10:41:54.693932Z","end":"2026-06-16T10:41:54.827488Z","steps":["trace[729828244] 'process raft request' (duration: 133.36069ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:41:56.969148Z","caller":"traceutil/trace.go:171","msg":"trace[1408831630] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"132.599165ms","start":"2026-06-16T10:41:56.836501Z","end":"2026-06-16T10:41:56.9691Z","steps":["trace[1408831630] 'process raft request' (duration: 132.34305ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:24.149794Z","caller":"traceutil/trace.go:171","msg":"trace[777645515] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"284.745149ms","start":"2026-06-16T10:42:23.865011Z","end":"2026-06-16T10:42:24.149756Z","steps":["trace[777645515] 'process raft request' (duration: 284.050135ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:24.351792Z","caller":"traceutil/trace.go:171","msg":"trace[1658544733] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"122.895857ms","start":"2026-06-16T10:42:24.228869Z","end":"2026-06-16T10:42:24.351765Z","steps":["trace[1658544733] 'process raft request' (duration: 121.075192ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:37.56695Z","caller":"traceutil/trace.go:171","msg":"trace[1074625734] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"104.306078ms","start":"2026-06-16T10:42:37.462618Z","end":"2026-06-16T10:42:37.566924Z","steps":["trace[1074625734] 'process raft request' (duration: 96.913775ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:37.616492Z","caller":"traceutil/trace.go:171","msg":"trace[1933200198] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"144.625837ms","start":"2026-06-16T10:42:37.471821Z","end":"2026-06-16T10:42:37.616446Z","steps":["trace[1933200198] 'process raft request' (duration: 144.398532ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:38.710341Z","caller":"traceutil/trace.go:171","msg":"trace[813668785] linearizableReadLoop","detail":"{readStateIndex:9144; appliedIndex:9143; }","duration":"152.250216ms","start":"2026-06-16T10:42:38.55806Z","end":"2026-06-16T10:42:38.710311Z","steps":["trace[813668785] 'read index received' (duration: 151.93662ms)","trace[813668785] 'applied index is now lower than readState.Index' (duration: 312.006µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:42:38.710416Z","caller":"traceutil/trace.go:171","msg":"trace[661790572] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"246.031419ms","start":"2026-06-16T10:42:38.464354Z","end":"2026-06-16T10:42:38.710386Z","steps":["trace[661790572] 'process raft request' (duration: 245.759804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:42:38.710675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.06786ms","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-16T10:42:38.710703Z","caller":"traceutil/trace.go:171","msg":"trace[2124969629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8826; }","duration":"125.131251ms","start":"2026-06-16T10:42:38.585565Z","end":"2026-06-16T10:42:38.710696Z","steps":["trace[2124969629] 'agreement among raft nodes before linearized reading' (duration: 124.976808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:42:38.710757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.677914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-16T10:42:38.710973Z","caller":"traceutil/trace.go:171","msg":"trace[369139689] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8826; }","duration":"152.950889ms","start":"2026-06-16T10:42:38.558009Z","end":"2026-06-16T10:42:38.71096Z","steps":["trace[369139689] 'agreement among raft nodes before linearized reading' (duration: 152.444529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:42:38.710929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.754992ms","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-16T10:42:38.711088Z","caller":"traceutil/trace.go:171","msg":"trace[180104537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8826; }","duration":"127.058278ms","start":"2026-06-16T10:42:38.584006Z","end":"2026-06-16T10:42:38.711064Z","steps":["trace[180104537] 'agreement among raft nodes before linearized reading' (duration: 126.528858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:42:38.710758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.265375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T10:42:38.711156Z","caller":"traceutil/trace.go:171","msg":"trace[1647665549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8826; }","duration":"137.693953ms","start":"2026-06-16T10:42:38.573448Z","end":"2026-06-16T10:42:38.711142Z","steps":["trace[1647665549] 'agreement among raft nodes before linearized reading' (duration: 137.134413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:42:38.710839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.995199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-16T10:42:38.711229Z","caller":"traceutil/trace.go:171","msg":"trace[9193432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8826; }","duration":"138.410737ms","start":"2026-06-16T10:42:38.572805Z","end":"2026-06-16T10:42:38.711215Z","steps":["trace[9193432] 'agreement among raft nodes before linearized reading' (duration: 137.686343ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:42:40.46499Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d1.wal"} {"level":"info","ts":"2026-06-16T10:43:58.093149Z","caller":"traceutil/trace.go:171","msg":"trace[1966785376] transaction","detail":"{read_only:false; response_revision:9593; number_of_response:1; }","duration":"128.335293ms","start":"2026-06-16T10:43:57.964779Z","end":"2026-06-16T10:43:58.093114Z","steps":["trace[1966785376] 'process raft request' (duration: 127.941015ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:44:06.370884Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T10:44:06.37852Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-16T10:44:06.378716Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-16T10:46:15.993339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8114} {"level":"info","ts":"2026-06-16T10:46:16.062406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8114,"took":"65.812288ms","hash":203525997,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":28450816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-16T10:46:16.062506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":203525997,"revision":8114,"compact-revision":6045} {"level":"info","ts":"2026-06-16T10:49:21.152842Z","caller":"traceutil/trace.go:171","msg":"trace[779388684] transaction","detail":"{read_only:false; response_revision:11933; number_of_response:1; }","duration":"112.156102ms","start":"2026-06-16T10:49:21.040639Z","end":"2026-06-16T10:49:21.152795Z","steps":["trace[779388684] 'process raft request' (duration: 111.785285ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:49:21.310377Z","caller":"traceutil/trace.go:171","msg":"trace[905906638] transaction","detail":"{read_only:false; response_revision:11936; number_of_response:1; }","duration":"152.564584ms","start":"2026-06-16T10:49:21.157782Z","end":"2026-06-16T10:49:21.310347Z","steps":["trace[905906638] 'process raft request' (duration: 152.521943ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:49:21.310558Z","caller":"traceutil/trace.go:171","msg":"trace[1021294454] linearizableReadLoop","detail":"{readStateIndex:12348; appliedIndex:12346; }","duration":"157.860615ms","start":"2026-06-16T10:49:21.152666Z","end":"2026-06-16T10:49:21.310527Z","steps":["trace[1021294454] 'read index received' (duration: 122.029041ms)","trace[1021294454] 'applied index is now lower than readState.Index' (duration: 35.825104ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:49:21.310557Z","caller":"traceutil/trace.go:171","msg":"trace[775098107] transaction","detail":"{read_only:false; response_revision:11934; number_of_response:1; }","duration":"230.886139ms","start":"2026-06-16T10:49:21.079612Z","end":"2026-06-16T10:49:21.310498Z","steps":["trace[775098107] 'process raft request' (duration: 195.060385ms)","trace[775098107] 'compare' (duration: 35.394365ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:49:21.31057Z","caller":"traceutil/trace.go:171","msg":"trace[1540217977] transaction","detail":"{read_only:false; response_revision:11935; number_of_response:1; }","duration":"222.315395ms","start":"2026-06-16T10:49:21.088207Z","end":"2026-06-16T10:49:21.310522Z","steps":["trace[1540217977] 'process raft request' (duration: 222.0511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:49:21.310764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.049449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T10:49:21.310831Z","caller":"traceutil/trace.go:171","msg":"trace[696777879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11936; }","duration":"245.173692ms","start":"2026-06-16T10:49:21.065643Z","end":"2026-06-16T10:49:21.310816Z","steps":["trace[696777879] 'agreement among raft nodes before linearized reading' (duration: 244.956508ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:49:46.907865Z","caller":"traceutil/trace.go:171","msg":"trace[994924884] linearizableReadLoop","detail":"{readStateIndex:12493; appliedIndex:12492; }","duration":"104.899952ms","start":"2026-06-16T10:49:46.802888Z","end":"2026-06-16T10:49:46.907788Z","steps":["trace[994924884] 'read index received' (duration: 24.15371ms)","trace[994924884] 'applied index is now lower than readState.Index' (duration: 80.744572ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:49:46.907868Z","caller":"traceutil/trace.go:171","msg":"trace[894288817] transaction","detail":"{read_only:false; response_revision:12075; number_of_response:1; }","duration":"118.37921ms","start":"2026-06-16T10:49:46.78945Z","end":"2026-06-16T10:49:46.907829Z","steps":["trace[894288817] 'process raft request' (duration: 37.684229ms)","trace[894288817] 'compare' (duration: 80.448017ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:49:46.908054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.148318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:49:46.908122Z","caller":"traceutil/trace.go:171","msg":"trace[1221608683] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:12075; }","duration":"105.288549ms","start":"2026-06-16T10:49:46.80282Z","end":"2026-06-16T10:49:46.908109Z","steps":["trace[1221608683] 'agreement among raft nodes before linearized reading' (duration: 105.135417ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:51:15.99741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10520} {"level":"info","ts":"2026-06-16T10:51:16.076049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10520,"took":"76.60691ms","hash":542612288,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-16T10:51:16.076132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":542612288,"revision":10520,"compact-revision":8114} {"level":"info","ts":"2026-06-16T10:56:16.003166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12660} {"level":"info","ts":"2026-06-16T10:56:16.07608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12660,"took":"70.390322ms","hash":1397232576,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":27365376,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-16T10:56:16.076163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1397232576,"revision":12660,"compact-revision":10520} {"level":"info","ts":"2026-06-16T10:56:21.837827Z","caller":"traceutil/trace.go:171","msg":"trace[1412971073] transaction","detail":"{read_only:false; response_revision:14720; number_of_response:1; }","duration":"105.526258ms","start":"2026-06-16T10:56:21.732272Z","end":"2026-06-16T10:56:21.837799Z","steps":["trace[1412971073] 'process raft request' (duration: 105.312954ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:56:21.982715Z","caller":"traceutil/trace.go:171","msg":"trace[1709280904] transaction","detail":"{read_only:false; response_revision:14721; number_of_response:1; }","duration":"139.289307ms","start":"2026-06-16T10:56:21.843375Z","end":"2026-06-16T10:56:21.982664Z","steps":["trace[1709280904] 'process raft request' (duration: 112.279738ms)","trace[1709280904] 'compare' (duration: 26.785455ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:56:21.983448Z","caller":"traceutil/trace.go:171","msg":"trace[253081467] transaction","detail":"{read_only:false; response_revision:14722; number_of_response:1; }","duration":"139.774746ms","start":"2026-06-16T10:56:21.843643Z","end":"2026-06-16T10:56:21.983417Z","steps":["trace[253081467] 'process raft request' (duration: 139.551042ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:56:21.983572Z","caller":"traceutil/trace.go:171","msg":"trace[1827265678] transaction","detail":"{read_only:false; response_revision:14723; number_of_response:1; }","duration":"139.601563ms","start":"2026-06-16T10:56:21.84395Z","end":"2026-06-16T10:56:21.983551Z","steps":["trace[1827265678] 'process raft request' (duration: 139.330388ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:56:46.312072Z","caller":"traceutil/trace.go:171","msg":"trace[282652378] linearizableReadLoop","detail":"{readStateIndex:15566; appliedIndex:15565; }","duration":"275.210577ms","start":"2026-06-16T10:56:46.036841Z","end":"2026-06-16T10:56:46.312051Z","steps":["trace[282652378] 'read index received' (duration: 275.015774ms)","trace[282652378] 'applied index is now lower than readState.Index' (duration: 194.083µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:56:46.312408Z","caller":"traceutil/trace.go:171","msg":"trace[280011056] transaction","detail":"{read_only:false; response_revision:15039; number_of_response:1; }","duration":"388.453434ms","start":"2026-06-16T10:56:45.923945Z","end":"2026-06-16T10:56:46.312399Z","steps":["trace[280011056] 'process raft request' (duration: 387.975534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:56:46.312535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:56:45.923925Z","time spent":"388.510805ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:56:46.312549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.667566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61fb89f3-96a7-437e-8b2c-2e07cff3b079\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-06-16T10:56:46.312697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.023736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T10:56:46.312761Z","caller":"traceutil/trace.go:171","msg":"trace[394963399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15039; }","duration":"197.130018ms","start":"2026-06-16T10:56:46.115608Z","end":"2026-06-16T10:56:46.312738Z","steps":["trace[394963399] 'agreement among raft nodes before linearized reading' (duration: 196.642869ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:56:46.312691Z","caller":"traceutil/trace.go:171","msg":"trace[189721333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61fb89f3-96a7-437e-8b2c-2e07cff3b079; range_end:; response_count:1; response_revision:15039; }","duration":"275.884791ms","start":"2026-06-16T10:56:46.036775Z","end":"2026-06-16T10:56:46.31266Z","steps":["trace[189721333] 'agreement among raft nodes before linearized reading' (duration: 275.423651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:56:46.312595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.336164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T10:56:46.313628Z","caller":"traceutil/trace.go:171","msg":"trace[778008494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15039; }","duration":"195.394954ms","start":"2026-06-16T10:56:46.11819Z","end":"2026-06-16T10:56:46.313585Z","steps":["trace[778008494] 'agreement among raft nodes before linearized reading' (duration: 194.243522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:56:46.312582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.430638ms","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-16T10:56:46.313853Z","caller":"traceutil/trace.go:171","msg":"trace[29007130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15039; }","duration":"192.692833ms","start":"2026-06-16T10:56:46.121097Z","end":"2026-06-16T10:56:46.31379Z","steps":["trace[29007130] 'agreement among raft nodes before linearized reading' (duration: 191.341238ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:01:16.011473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14678} {"level":"info","ts":"2026-06-16T11:01:16.092764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14678,"took":"76.104952ms","hash":1768787142,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T11:01:16.092847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1768787142,"revision":14678,"compact-revision":12660} {"level":"info","ts":"2026-06-16T11:04:19.028983Z","caller":"traceutil/trace.go:171","msg":"trace[1237475420] linearizableReadLoop","detail":"{readStateIndex:19209; appliedIndex:19208; }","duration":"118.484036ms","start":"2026-06-16T11:04:18.910476Z","end":"2026-06-16T11:04:19.02896Z","steps":["trace[1237475420] 'read index received' (duration: 118.287792ms)","trace[1237475420] 'applied index is now lower than readState.Index' (duration: 195.364µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:04:19.029179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.689961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T11:04:19.029216Z","caller":"traceutil/trace.go:171","msg":"trace[1193971106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18573; }","duration":"118.742682ms","start":"2026-06-16T11:04:18.910465Z","end":"2026-06-16T11:04:19.029207Z","steps":["trace[1193971106] 'agreement among raft nodes before linearized reading' (duration: 118.607499ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:04:19.029132Z","caller":"traceutil/trace.go:171","msg":"trace[2000239782] transaction","detail":"{read_only:false; response_revision:18573; number_of_response:1; }","duration":"142.134993ms","start":"2026-06-16T11:04:18.886932Z","end":"2026-06-16T11:04:19.029067Z","steps":["trace[2000239782] 'process raft request' (duration: 141.890478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:04:19.029396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.903185ms","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-16T11:04:19.029498Z","caller":"traceutil/trace.go:171","msg":"trace[552573147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18573; }","duration":"106.035797ms","start":"2026-06-16T11:04:18.923432Z","end":"2026-06-16T11:04:19.029467Z","steps":["trace[552573147] 'agreement among raft nodes before linearized reading' (duration: 105.74956ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:04:25.373509Z","caller":"traceutil/trace.go:171","msg":"trace[1240961644] transaction","detail":"{read_only:false; response_revision:18613; number_of_response:1; }","duration":"125.001192ms","start":"2026-06-16T11:04:25.248478Z","end":"2026-06-16T11:04:25.373479Z","steps":["trace[1240961644] 'process raft request' (duration: 124.816438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:04:26.085509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.618928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:04:26.085706Z","caller":"traceutil/trace.go:171","msg":"trace[364211797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18614; }","duration":"162.875744ms","start":"2026-06-16T11:04:25.922803Z","end":"2026-06-16T11:04:26.085678Z","steps":["trace[364211797] 'range keys from in-memory index tree' (duration: 162.531317ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:04:26.270398Z","caller":"traceutil/trace.go:171","msg":"trace[1214519450] linearizableReadLoop","detail":"{readStateIndex:19253; appliedIndex:19252; }","duration":"163.20831ms","start":"2026-06-16T11:04:26.107147Z","end":"2026-06-16T11:04:26.270355Z","steps":["trace[1214519450] 'read index received' (duration: 162.949705ms)","trace[1214519450] 'applied index is now lower than readState.Index' (duration: 257.485µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:04:26.270494Z","caller":"traceutil/trace.go:171","msg":"trace[144283318] transaction","detail":"{read_only:false; response_revision:18615; number_of_response:1; }","duration":"179.931993ms","start":"2026-06-16T11:04:26.09052Z","end":"2026-06-16T11:04:26.270452Z","steps":["trace[144283318] 'process raft request' (duration: 179.555716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:04:26.271764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.585328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:955509"} {"level":"info","ts":"2026-06-16T11:04:26.271876Z","caller":"traceutil/trace.go:171","msg":"trace[1368256365] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18615; }","duration":"164.75589ms","start":"2026-06-16T11:04:26.107096Z","end":"2026-06-16T11:04:26.271852Z","steps":["trace[1368256365] 'agreement among raft nodes before linearized reading' (duration: 163.386663ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:04:26.349038Z","caller":"traceutil/trace.go:171","msg":"trace[100926990] transaction","detail":"{read_only:false; response_revision:18616; number_of_response:1; }","duration":"156.02204ms","start":"2026-06-16T11:04:26.192983Z","end":"2026-06-16T11:04:26.349005Z","steps":["trace[100926990] 'process raft request' (duration: 131.855015ms)","trace[100926990] 'compare' (duration: 23.798168ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:04:26.349051Z","caller":"traceutil/trace.go:171","msg":"trace[1504051085] transaction","detail":"{read_only:false; response_revision:18617; number_of_response:1; }","duration":"154.796578ms","start":"2026-06-16T11:04:26.194227Z","end":"2026-06-16T11:04:26.349024Z","steps":["trace[1504051085] 'process raft request' (duration: 154.658095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:04:26.349211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.957343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-16T11:04:26.349352Z","caller":"traceutil/trace.go:171","msg":"trace[1231238037] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18617; }","duration":"108.189178ms","start":"2026-06-16T11:04:26.24113Z","end":"2026-06-16T11:04:26.349319Z","steps":["trace[1231238037] 'agreement among raft nodes before linearized reading' (duration: 107.834481ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:05:27.120187Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T11:05:27.125601Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-16T11:05:27.12571Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-16T11:06:16.017685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17372} {"level":"info","ts":"2026-06-16T11:06:16.120586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17372,"took":"99.415009ms","hash":3410493129,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":34889728,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-16T11:06:16.120648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3410493129,"revision":17372,"compact-revision":14678} {"level":"info","ts":"2026-06-16T11:10:26.284498Z","caller":"traceutil/trace.go:171","msg":"trace[1687535170] transaction","detail":"{read_only:false; response_revision:21423; number_of_response:1; }","duration":"121.421054ms","start":"2026-06-16T11:10:26.163033Z","end":"2026-06-16T11:10:26.284454Z","steps":["trace[1687535170] 'process raft request' (duration: 120.967685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:10:26.578576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.410091ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:10:26.578748Z","caller":"traceutil/trace.go:171","msg":"trace[1151195072] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21423; }","duration":"226.589454ms","start":"2026-06-16T11:10:26.35213Z","end":"2026-06-16T11:10:26.578719Z","steps":["trace[1151195072] 'range keys from in-memory index tree' (duration: 226.39156ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:10:26.583421Z","caller":"traceutil/trace.go:171","msg":"trace[484370513] transaction","detail":"{read_only:false; response_revision:21424; number_of_response:1; }","duration":"121.838862ms","start":"2026-06-16T11:10:26.461536Z","end":"2026-06-16T11:10:26.583375Z","steps":["trace[484370513] 'process raft request' (duration: 121.668709ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:10:26.886976Z","caller":"traceutil/trace.go:171","msg":"trace[228161596] linearizableReadLoop","detail":"{readStateIndex:22157; appliedIndex:22156; }","duration":"159.788814ms","start":"2026-06-16T11:10:26.727171Z","end":"2026-06-16T11:10:26.88696Z","steps":["trace[228161596] 'read index received' (duration: 159.641192ms)","trace[228161596] 'applied index is now lower than readState.Index' (duration: 147.142µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:10:26.887094Z","caller":"traceutil/trace.go:171","msg":"trace[1165196463] transaction","detail":"{read_only:false; response_revision:21425; number_of_response:1; }","duration":"206.787051ms","start":"2026-06-16T11:10:26.680257Z","end":"2026-06-16T11:10:26.887044Z","steps":["trace[1165196463] 'process raft request' (duration: 206.606888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:10:26.887112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.948837ms","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-16T11:10:26.887234Z","caller":"traceutil/trace.go:171","msg":"trace[142626044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21425; }","duration":"160.12212ms","start":"2026-06-16T11:10:26.727103Z","end":"2026-06-16T11:10:26.887225Z","steps":["trace[142626044] 'agreement among raft nodes before linearized reading' (duration: 159.914717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:10:27.284361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.542576ms","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-16T11:10:27.284443Z","caller":"traceutil/trace.go:171","msg":"trace[656317572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21427; }","duration":"176.666309ms","start":"2026-06-16T11:10:27.107756Z","end":"2026-06-16T11:10:27.284422Z","steps":["trace[656317572] 'range keys from in-memory index tree' (duration: 176.370753ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:11:16.032742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19714} {"level":"info","ts":"2026-06-16T11:11:16.14796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19714,"took":"111.596604ms","hash":1613503651,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30420992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-16T11:11:16.148052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613503651,"revision":19714,"compact-revision":17372} {"level":"info","ts":"2026-06-16T11:14:26.918896Z","caller":"traceutil/trace.go:171","msg":"trace[173830195] transaction","detail":"{read_only:false; response_revision:23858; number_of_response:1; }","duration":"154.585038ms","start":"2026-06-16T11:14:26.764273Z","end":"2026-06-16T11:14:26.918858Z","steps":["trace[173830195] 'process raft request' (duration: 154.271652ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:14:26.919091Z","caller":"traceutil/trace.go:171","msg":"trace[1448481006] linearizableReadLoop","detail":"{readStateIndex:24665; appliedIndex:24662; }","duration":"145.598986ms","start":"2026-06-16T11:14:26.773443Z","end":"2026-06-16T11:14:26.919042Z","steps":["trace[1448481006] 'read index received' (duration: 27.721741ms)","trace[1448481006] 'applied index is now lower than readState.Index' (duration: 117.874125ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:14:26.919071Z","caller":"traceutil/trace.go:171","msg":"trace[1479641916] transaction","detail":"{read_only:false; response_revision:23859; number_of_response:1; }","duration":"148.173536ms","start":"2026-06-16T11:14:26.770876Z","end":"2026-06-16T11:14:26.919049Z","steps":["trace[1479641916] 'process raft request' (duration: 147.904531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:14:26.919463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.986834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:70572"} {"level":"info","ts":"2026-06-16T11:14:26.919561Z","caller":"traceutil/trace.go:171","msg":"trace[1516171528] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23859; }","duration":"146.113907ms","start":"2026-06-16T11:14:26.773418Z","end":"2026-06-16T11:14:26.919532Z","steps":["trace[1516171528] 'agreement among raft nodes before linearized reading' (duration: 145.724898ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:14:26.959702Z","caller":"traceutil/trace.go:171","msg":"trace[1494995299] transaction","detail":"{read_only:false; response_revision:23861; number_of_response:1; }","duration":"107.703262ms","start":"2026-06-16T11:14:26.851967Z","end":"2026-06-16T11:14:26.95967Z","steps":["trace[1494995299] 'process raft request' (duration: 107.560089ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:14:26.959725Z","caller":"traceutil/trace.go:171","msg":"trace[1398459781] transaction","detail":"{read_only:false; response_revision:23862; number_of_response:1; }","duration":"107.335644ms","start":"2026-06-16T11:14:26.852364Z","end":"2026-06-16T11:14:26.9597Z","steps":["trace[1398459781] 'process raft request' (duration: 107.226302ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:14:26.959812Z","caller":"traceutil/trace.go:171","msg":"trace[1706641016] transaction","detail":"{read_only:false; response_revision:23860; number_of_response:1; }","duration":"146.933543ms","start":"2026-06-16T11:14:26.812831Z","end":"2026-06-16T11:14:26.959765Z","steps":["trace[1706641016] 'process raft request' (duration: 125.41658ms)","trace[1706641016] 'compare' (duration: 21.128845ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:14:28.589566Z","caller":"traceutil/trace.go:171","msg":"trace[726427922] transaction","detail":"{read_only:false; response_revision:23902; number_of_response:1; }","duration":"239.535894ms","start":"2026-06-16T11:14:28.350005Z","end":"2026-06-16T11:14:28.589541Z","steps":["trace[726427922] 'process raft request' (duration: 229.032683ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:14:28.589627Z","caller":"traceutil/trace.go:171","msg":"trace[563692463] transaction","detail":"{read_only:false; response_revision:23903; number_of_response:1; }","duration":"181.824009ms","start":"2026-06-16T11:14:28.407778Z","end":"2026-06-16T11:14:28.589602Z","steps":["trace[563692463] 'process raft request' (duration: 181.717917ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:16:16.03909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21949} {"level":"info","ts":"2026-06-16T11:16:16.155777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21949,"took":"104.49427ms","hash":3672219707,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":43855872,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-16T11:16:16.156244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672219707,"revision":21949,"compact-revision":19714} {"level":"info","ts":"2026-06-16T11:17:06.2268Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066df.wal"} {"level":"info","ts":"2026-06-16T11:18:10.402543Z","caller":"traceutil/trace.go:171","msg":"trace[332659123] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"123.86067ms","start":"2026-06-16T11:18:10.278657Z","end":"2026-06-16T11:18:10.402518Z","steps":["trace[332659123] 'process raft request' (duration: 123.710617ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:11.146671Z","caller":"traceutil/trace.go:171","msg":"trace[140766643] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26978; }","duration":"248.548147ms","start":"2026-06-16T11:18:10.898099Z","end":"2026-06-16T11:18:11.146647Z","steps":["trace[140766643] 'read index received' (duration: 248.366353ms)","trace[140766643] 'applied index is now lower than readState.Index' (duration: 180.904µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:11.146842Z","caller":"traceutil/trace.go:171","msg":"trace[551355518] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"290.234785ms","start":"2026-06-16T11:18:10.856523Z","end":"2026-06-16T11:18:11.146758Z","steps":["trace[551355518] 'process raft request' (duration: 289.96428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:11.146875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.75581ms","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-16T11:18:11.14691Z","caller":"traceutil/trace.go:171","msg":"trace[1568236169] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26115; }","duration":"248.843332ms","start":"2026-06-16T11:18:10.898054Z","end":"2026-06-16T11:18:11.146897Z","steps":["trace[1568236169] 'agreement among raft nodes before linearized reading' (duration: 248.70456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:11.146892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.078158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:11.146974Z","caller":"traceutil/trace.go:171","msg":"trace[2014925812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26115; }","duration":"224.21718ms","start":"2026-06-16T11:18:10.922732Z","end":"2026-06-16T11:18:11.146949Z","steps":["trace[2014925812] 'agreement among raft nodes before linearized reading' (duration: 224.072508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:11.146965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.797788ms","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-16T11:18:11.14706Z","caller":"traceutil/trace.go:171","msg":"trace[1908580701] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26115; }","duration":"113.957311ms","start":"2026-06-16T11:18:11.033084Z","end":"2026-06-16T11:18:11.147041Z","steps":["trace[1908580701] 'agreement among raft nodes before linearized reading' (duration: 113.745587ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:15.68256Z","caller":"traceutil/trace.go:171","msg":"trace[1039659502] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"201.652949ms","start":"2026-06-16T11:18:15.480874Z","end":"2026-06-16T11:18:15.682527Z","steps":["trace[1039659502] 'process raft request' (duration: 138.216755ms)","trace[1039659502] 'compare' (duration: 63.177739ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:15.68268Z","caller":"traceutil/trace.go:171","msg":"trace[276989842] linearizableReadLoop","detail":"{readStateIndex:27016; appliedIndex:27014; }","duration":"144.281241ms","start":"2026-06-16T11:18:15.538372Z","end":"2026-06-16T11:18:15.682654Z","steps":["trace[276989842] 'read index received' (duration: 80.683854ms)","trace[276989842] 'applied index is now lower than readState.Index' (duration: 63.595467ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:15.68287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.480735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T11:18:15.682913Z","caller":"traceutil/trace.go:171","msg":"trace[1271468798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26151; }","duration":"144.564097ms","start":"2026-06-16T11:18:15.538337Z","end":"2026-06-16T11:18:15.682901Z","steps":["trace[1271468798] 'agreement among raft nodes before linearized reading' (duration: 144.397414ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:15.682952Z","caller":"traceutil/trace.go:171","msg":"trace[1966745017] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"177.456265ms","start":"2026-06-16T11:18:15.505464Z","end":"2026-06-16T11:18:15.682921Z","steps":["trace[1966745017] 'process raft request' (duration: 176.988577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:15.96028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.741747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:15.960605Z","caller":"traceutil/trace.go:171","msg":"trace[589008642] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"242.278266ms","start":"2026-06-16T11:18:15.718299Z","end":"2026-06-16T11:18:15.960577Z","steps":["trace[589008642] 'process raft request' (duration: 242.107252ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:15.960584Z","caller":"traceutil/trace.go:171","msg":"trace[1649012103] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"273.461323ms","start":"2026-06-16T11:18:15.687036Z","end":"2026-06-16T11:18:15.960497Z","steps":["trace[1649012103] 'process raft request' (duration: 136.38488ms)","trace[1649012103] 'compare' (duration: 136.510072ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:15.983684Z","caller":"traceutil/trace.go:171","msg":"trace[1299341618] linearizableReadLoop","detail":"{readStateIndex:27020; appliedIndex:27017; }","duration":"174.434378ms","start":"2026-06-16T11:18:15.809225Z","end":"2026-06-16T11:18:15.983659Z","steps":["trace[1299341618] 'read index received' (duration: 14.208552ms)","trace[1299341618] 'applied index is now lower than readState.Index' (duration: 160.225026ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:15.983945Z","caller":"traceutil/trace.go:171","msg":"trace[630759978] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"257.056418ms","start":"2026-06-16T11:18:15.726879Z","end":"2026-06-16T11:18:15.983935Z","steps":["trace[630759978] 'process raft request' (duration: 256.60785ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:15.984138Z","caller":"traceutil/trace.go:171","msg":"trace[1911023453] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"166.149059ms","start":"2026-06-16T11:18:15.817981Z","end":"2026-06-16T11:18:15.98413Z","steps":["trace[1911023453] 'process raft request' (duration: 165.64103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:15.984327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.093971ms","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-16T11:18:15.98436Z","caller":"traceutil/trace.go:171","msg":"trace[1468092036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26156; }","duration":"175.177133ms","start":"2026-06-16T11:18:15.809172Z","end":"2026-06-16T11:18:15.984349Z","steps":["trace[1468092036] 'agreement among raft nodes before linearized reading' (duration: 175.056011ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.104236Z","caller":"traceutil/trace.go:171","msg":"trace[149580305] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"280.08161ms","start":"2026-06-16T11:18:15.824117Z","end":"2026-06-16T11:18:16.104199Z","steps":["trace[149580305] 'process raft request' (duration: 278.763835ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.104453Z","caller":"traceutil/trace.go:171","msg":"trace[1585615100] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"115.387738ms","start":"2026-06-16T11:18:15.989032Z","end":"2026-06-16T11:18:16.10442Z","steps":["trace[1585615100] 'process raft request' (duration: 115.066002ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.10455Z","caller":"traceutil/trace.go:171","msg":"trace[2065872310] linearizableReadLoop","detail":"{readStateIndex:27022; appliedIndex:27021; }","duration":"120.783741ms","start":"2026-06-16T11:18:15.983744Z","end":"2026-06-16T11:18:16.104528Z","steps":["trace[2065872310] 'read index received' (duration: 119.14678ms)","trace[2065872310] 'applied index is now lower than readState.Index' (duration: 1.634821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:16.104684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.279517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T11:18:16.1047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.260997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:16.104734Z","caller":"traceutil/trace.go:171","msg":"trace[1761001853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26158; }","duration":"183.37333ms","start":"2026-06-16T11:18:15.921343Z","end":"2026-06-16T11:18:16.104717Z","steps":["trace[1761001853] 'agreement among raft nodes before linearized reading' (duration: 183.226456ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.104759Z","caller":"traceutil/trace.go:171","msg":"trace[1068233441] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26158; }","duration":"196.41275ms","start":"2026-06-16T11:18:15.908329Z","end":"2026-06-16T11:18:16.104742Z","steps":["trace[1068233441] 'agreement among raft nodes before linearized reading' (duration: 196.257556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:16.806001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.8846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:16.806111Z","caller":"traceutil/trace.go:171","msg":"trace[864404331] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"223.745412ms","start":"2026-06-16T11:18:16.582346Z","end":"2026-06-16T11:18:16.806091Z","steps":["trace[864404331] 'process raft request' (duration: 63.693189ms)","trace[864404331] 'compare' (duration: 159.620655ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:16.807112Z","caller":"traceutil/trace.go:171","msg":"trace[1887519990] linearizableReadLoop","detail":"{readStateIndex:27026; appliedIndex:27025; }","duration":"125.282647ms","start":"2026-06-16T11:18:16.681811Z","end":"2026-06-16T11:18:16.807094Z","steps":["trace[1887519990] 'read index received' (duration: 125.166745ms)","trace[1887519990] 'applied index is now lower than readState.Index' (duration: 115.152µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:16.80735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.520082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-16T11:18:16.807374Z","caller":"traceutil/trace.go:171","msg":"trace[1109237427] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26161; }","duration":"125.591412ms","start":"2026-06-16T11:18:16.681776Z","end":"2026-06-16T11:18:16.807367Z","steps":["trace[1109237427] 'agreement among raft nodes before linearized reading' (duration: 125.382248ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.808126Z","caller":"traceutil/trace.go:171","msg":"trace[1330250135] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"224.798503ms","start":"2026-06-16T11:18:16.58329Z","end":"2026-06-16T11:18:16.808089Z","steps":["trace[1330250135] 'process raft request' (duration: 223.737292ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:16.975143Z","caller":"traceutil/trace.go:171","msg":"trace[224111313] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"146.252948ms","start":"2026-06-16T11:18:16.828863Z","end":"2026-06-16T11:18:16.975116Z","steps":["trace[224111313] 'process raft request' (duration: 131.447935ms)","trace[224111313] 'compare' (duration: 14.720672ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:17.130434Z","caller":"traceutil/trace.go:171","msg":"trace[2065879574] linearizableReadLoop","detail":"{readStateIndex:27028; appliedIndex:27026; }","duration":"229.001103ms","start":"2026-06-16T11:18:16.901403Z","end":"2026-06-16T11:18:17.130404Z","steps":["trace[2065879574] 'read index received' (duration: 58.920548ms)","trace[2065879574] 'applied index is now lower than readState.Index' (duration: 170.079555ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:17.130703Z","caller":"traceutil/trace.go:171","msg":"trace[1703113026] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"255.076321ms","start":"2026-06-16T11:18:16.875615Z","end":"2026-06-16T11:18:17.130691Z","steps":["trace[1703113026] 'process raft request' (duration: 250.417542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:17.130909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.798857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T11:18:17.131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.595004ms","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":"warn","ts":"2026-06-16T11:18:17.131002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.624861ms","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-16T11:18:17.131012Z","caller":"traceutil/trace.go:171","msg":"trace[1856387649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26163; }","duration":"207.906679ms","start":"2026-06-16T11:18:16.923075Z","end":"2026-06-16T11:18:17.130982Z","steps":["trace[1856387649] 'agreement among raft nodes before linearized reading' (duration: 207.736655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:17.131057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.087332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:17.131054Z","caller":"traceutil/trace.go:171","msg":"trace[775673151] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26163; }","duration":"229.636194ms","start":"2026-06-16T11:18:16.901395Z","end":"2026-06-16T11:18:17.131032Z","steps":["trace[775673151] 'agreement among raft nodes before linearized reading' (duration: 229.572303ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:17.131067Z","caller":"traceutil/trace.go:171","msg":"trace[2067280992] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26163; }","duration":"129.689482ms","start":"2026-06-16T11:18:17.001357Z","end":"2026-06-16T11:18:17.131046Z","steps":["trace[2067280992] 'agreement among raft nodes before linearized reading' (duration: 129.478778ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:17.131102Z","caller":"traceutil/trace.go:171","msg":"trace[2064900386] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26163; }","duration":"185.179373ms","start":"2026-06-16T11:18:16.945908Z","end":"2026-06-16T11:18:17.131088Z","steps":["trace[2064900386] 'agreement among raft nodes before linearized reading' (duration: 185.094872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:17.130906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:17.131405Z","caller":"traceutil/trace.go:171","msg":"trace[233712027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26163; }","duration":"107.101929ms","start":"2026-06-16T11:18:17.024291Z","end":"2026-06-16T11:18:17.131393Z","steps":["trace[233712027] 'agreement among raft nodes before linearized reading' (duration: 106.542409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:17.464444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.264773ms","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-16T11:18:17.464503Z","caller":"traceutil/trace.go:171","msg":"trace[1325763762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26163; }","duration":"234.376866ms","start":"2026-06-16T11:18:17.230113Z","end":"2026-06-16T11:18:17.46449Z","steps":["trace[1325763762] 'range keys from in-memory index tree' (duration: 234.11003ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:17.617012Z","caller":"traceutil/trace.go:171","msg":"trace[476511738] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"148.337009ms","start":"2026-06-16T11:18:17.468634Z","end":"2026-06-16T11:18:17.616971Z","steps":["trace[476511738] 'process raft request' (duration: 147.984483ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:17.823835Z","caller":"traceutil/trace.go:171","msg":"trace[1090147915] linearizableReadLoop","detail":"{readStateIndex:27030; appliedIndex:27029; }","duration":"176.522228ms","start":"2026-06-16T11:18:17.647288Z","end":"2026-06-16T11:18:17.823811Z","steps":["trace[1090147915] 'read index received' (duration: 142.872204ms)","trace[1090147915] 'applied index is now lower than readState.Index' (duration: 33.648734ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:17.823854Z","caller":"traceutil/trace.go:171","msg":"trace[1620700856] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"341.24563ms","start":"2026-06-16T11:18:17.482575Z","end":"2026-06-16T11:18:17.823821Z","steps":["trace[1620700856] 'process raft request' (duration: 307.568385ms)","trace[1620700856] 'compare' (duration: 33.43014ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:17.824014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.725642ms","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-16T11:18:17.824042Z","caller":"traceutil/trace.go:171","msg":"trace[1289587241] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26165; }","duration":"176.833454ms","start":"2026-06-16T11:18:17.647201Z","end":"2026-06-16T11:18:17.824035Z","steps":["trace[1289587241] 'agreement among raft nodes before linearized reading' (duration: 176.707331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:17.824032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.482547Z","time spent":"341.377813ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:18.097316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.067676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:18.097454Z","caller":"traceutil/trace.go:171","msg":"trace[1927863134] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27030; }","duration":"273.548635ms","start":"2026-06-16T11:18:17.823888Z","end":"2026-06-16T11:18:18.097436Z","steps":["trace[1927863134] 'read index received' (duration: 163.363517ms)","trace[1927863134] 'applied index is now lower than readState.Index' (duration: 110.180508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:18.097575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.346721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:18.097615Z","caller":"traceutil/trace.go:171","msg":"trace[103670254] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26166; }","duration":"354.417442ms","start":"2026-06-16T11:18:17.743186Z","end":"2026-06-16T11:18:18.097603Z","steps":["trace[103670254] 'agreement among raft nodes before linearized reading' (duration: 354.327711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.097634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.400533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-06-16T11:18:18.097716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.54106ms","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-16T11:18:18.097759Z","caller":"traceutil/trace.go:171","msg":"trace[1031012812] 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:26166; }","duration":"359.617431ms","start":"2026-06-16T11:18:17.738131Z","end":"2026-06-16T11:18:18.097748Z","steps":["trace[1031012812] 'agreement among raft nodes before linearized reading' (duration: 359.55578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.097796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.738111Z","time spent":"359.678293ms","remote":"127.0.0.1:50578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-06-16T11:18:18.097803Z","caller":"traceutil/trace.go:171","msg":"trace[1981535715] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"368.319328ms","start":"2026-06-16T11:18:17.729437Z","end":"2026-06-16T11:18:18.097757Z","steps":["trace[1981535715] 'process raft request' (duration: 257.706652ms)","trace[1981535715] 'compare' (duration: 109.785861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:18.097855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.460234ms","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-16T11:18:18.097903Z","caller":"traceutil/trace.go:171","msg":"trace[1206016947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26166; }","duration":"411.539176ms","start":"2026-06-16T11:18:17.686353Z","end":"2026-06-16T11:18:18.097892Z","steps":["trace[1206016947] 'agreement among raft nodes before linearized reading' (duration: 411.411693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.097932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.686336Z","time spent":"411.590546ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T11:18:18.09795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.729401Z","time spent":"368.488741ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:18.097718Z","caller":"traceutil/trace.go:171","msg":"trace[1796383091] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26166; }","duration":"341.529766ms","start":"2026-06-16T11:18:17.756167Z","end":"2026-06-16T11:18:18.097697Z","steps":["trace[1796383091] 'agreement among raft nodes before linearized reading' (duration: 341.306902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.098098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.756145Z","time spent":"341.944243ms","remote":"127.0.0.1:46580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-06-16T11:18:18.097656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:17.743178Z","time spent":"354.467123ms","remote":"127.0.0.1:59636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"info","ts":"2026-06-16T11:18:18.099201Z","caller":"traceutil/trace.go:171","msg":"trace[490307044] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"133.436834ms","start":"2026-06-16T11:18:17.96574Z","end":"2026-06-16T11:18:18.099177Z","steps":["trace[490307044] 'process raft request' (duration: 133.30265ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.169568Z","caller":"traceutil/trace.go:171","msg":"trace[1702922726] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"178.272351ms","start":"2026-06-16T11:18:17.991269Z","end":"2026-06-16T11:18:18.169542Z","steps":["trace[1702922726] 'process raft request' (duration: 178.071468ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.169807Z","caller":"traceutil/trace.go:171","msg":"trace[1711573117] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"178.501736ms","start":"2026-06-16T11:18:17.991271Z","end":"2026-06-16T11:18:18.169773Z","steps":["trace[1711573117] 'process raft request' (duration: 178.221831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.169924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.905801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T11:18:18.16999Z","caller":"traceutil/trace.go:171","msg":"trace[1614379793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26169; }","duration":"205.013784ms","start":"2026-06-16T11:18:17.964959Z","end":"2026-06-16T11:18:18.169973Z","steps":["trace[1614379793] 'agreement among raft nodes before linearized reading' (duration: 204.80832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.405956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.948182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:18.406145Z","caller":"traceutil/trace.go:171","msg":"trace[1717345792] linearizableReadLoop","detail":"{readStateIndex:27036; appliedIndex:27034; }","duration":"236.401863ms","start":"2026-06-16T11:18:18.169728Z","end":"2026-06-16T11:18:18.40613Z","steps":["trace[1717345792] 'read index received' (duration: 88.188797ms)","trace[1717345792] 'applied index is now lower than readState.Index' (duration: 148.212006ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:18.406285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.484507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8188cb92-2d35-4b04-8c2a-d4b0722dd448\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-06-16T11:18:18.406318Z","caller":"traceutil/trace.go:171","msg":"trace[486582053] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8188cb92-2d35-4b04-8c2a-d4b0722dd448; range_end:; response_count:1; response_revision:26171; }","duration":"304.584879ms","start":"2026-06-16T11:18:18.101723Z","end":"2026-06-16T11:18:18.406308Z","steps":["trace[486582053] 'agreement among raft nodes before linearized reading' (duration: 304.456286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.406346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:18.101672Z","time spent":"304.66585ms","remote":"127.0.0.1:46570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-8188cb92-2d35-4b04-8c2a-d4b0722dd448\" "} {"level":"info","ts":"2026-06-16T11:18:18.40652Z","caller":"traceutil/trace.go:171","msg":"trace[857215234] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"304.307183ms","start":"2026-06-16T11:18:18.102201Z","end":"2026-06-16T11:18:18.406508Z","steps":["trace[857215234] 'process raft request' (duration: 155.727889ms)","trace[857215234] 'compare' (duration: 147.720527ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:18.406577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:18.102182Z","time spent":"304.366434ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:18.406699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.641884ms","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-16T11:18:18.406747Z","caller":"traceutil/trace.go:171","msg":"trace[513902459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26171; }","duration":"298.691185ms","start":"2026-06-16T11:18:18.108044Z","end":"2026-06-16T11:18:18.406735Z","steps":["trace[513902459] 'agreement among raft nodes before linearized reading' (duration: 298.577333ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.406893Z","caller":"traceutil/trace.go:171","msg":"trace[398845525] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"296.733149ms","start":"2026-06-16T11:18:18.110152Z","end":"2026-06-16T11:18:18.406886Z","steps":["trace[398845525] 'process raft request' (duration: 295.915443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.406714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.821185ms","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-16T11:18:18.407424Z","caller":"traceutil/trace.go:171","msg":"trace[1304155395] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26171; }","duration":"303.501659ms","start":"2026-06-16T11:18:18.103869Z","end":"2026-06-16T11:18:18.407371Z","steps":["trace[1304155395] 'agreement among raft nodes before linearized reading' (duration: 302.817755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:18.407587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:18.103859Z","time spent":"303.670062ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-06-16T11:18:18.553267Z","caller":"traceutil/trace.go:171","msg":"trace[685385605] linearizableReadLoop","detail":"{readStateIndex:27038; appliedIndex:27036; }","duration":"141.313485ms","start":"2026-06-16T11:18:18.411934Z","end":"2026-06-16T11:18:18.553247Z","steps":["trace[685385605] 'read index received' (duration: 125.481242ms)","trace[685385605] 'applied index is now lower than readState.Index' (duration: 15.831323ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:18.553376Z","caller":"traceutil/trace.go:171","msg":"trace[170210165] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"379.656385ms","start":"2026-06-16T11:18:18.173709Z","end":"2026-06-16T11:18:18.553366Z","steps":["trace[170210165] 'process raft request' (duration: 363.760241ms)","trace[170210165] 'compare' (duration: 15.606599ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:18.55345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:18.173689Z","time spent":"379.705427ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:18.5535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.535239ms","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":"info","ts":"2026-06-16T11:18:18.553587Z","caller":"traceutil/trace.go:171","msg":"trace[1662384707] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26173; }","duration":"141.636811ms","start":"2026-06-16T11:18:18.411929Z","end":"2026-06-16T11:18:18.553566Z","steps":["trace[1662384707] 'agreement among raft nodes before linearized reading' (duration: 141.482458ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.553708Z","caller":"traceutil/trace.go:171","msg":"trace[1020847500] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"141.908976ms","start":"2026-06-16T11:18:18.411755Z","end":"2026-06-16T11:18:18.553664Z","steps":["trace[1020847500] 'process raft request' (duration: 141.450897ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.927357Z","caller":"traceutil/trace.go:171","msg":"trace[398918059] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"115.806946ms","start":"2026-06-16T11:18:18.811532Z","end":"2026-06-16T11:18:18.927339Z","steps":["trace[398918059] 'process raft request' (duration: 115.667423ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:18.928613Z","caller":"traceutil/trace.go:171","msg":"trace[505051105] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"115.548232ms","start":"2026-06-16T11:18:18.813031Z","end":"2026-06-16T11:18:18.928579Z","steps":["trace[505051105] 'process raft request' (duration: 115.294476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:19.142006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.276524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-16T11:18:19.142256Z","caller":"traceutil/trace.go:171","msg":"trace[1689628560] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26176; }","duration":"128.56961ms","start":"2026-06-16T11:18:19.013672Z","end":"2026-06-16T11:18:19.142241Z","steps":["trace[1689628560] 'range keys from in-memory index tree' (duration: 128.138662ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:19.518576Z","caller":"traceutil/trace.go:171","msg":"trace[1578647168] linearizableReadLoop","detail":"{readStateIndex:27042; appliedIndex:27041; }","duration":"213.264512ms","start":"2026-06-16T11:18:19.305288Z","end":"2026-06-16T11:18:19.518553Z","steps":["trace[1578647168] 'read index received' (duration: 213.151049ms)","trace[1578647168] 'applied index is now lower than readState.Index' (duration: 112.513µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:19.518698Z","caller":"traceutil/trace.go:171","msg":"trace[1208521396] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"282.346794ms","start":"2026-06-16T11:18:19.236321Z","end":"2026-06-16T11:18:19.518668Z","steps":["trace[1208521396] 'process raft request' (duration: 282.076289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:19.518879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.584087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-16T11:18:19.518914Z","caller":"traceutil/trace.go:171","msg":"trace[1768331849] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:26177; }","duration":"213.671179ms","start":"2026-06-16T11:18:19.305233Z","end":"2026-06-16T11:18:19.518905Z","steps":["trace[1768331849] 'agreement among raft nodes before linearized reading' (duration: 213.558947ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:20.23666Z","caller":"traceutil/trace.go:171","msg":"trace[2061801330] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"134.053706ms","start":"2026-06-16T11:18:20.102588Z","end":"2026-06-16T11:18:20.236642Z","steps":["trace[2061801330] 'process raft request' (duration: 133.939703ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:20.422794Z","caller":"traceutil/trace.go:171","msg":"trace[1504706467] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"247.475625ms","start":"2026-06-16T11:18:20.175297Z","end":"2026-06-16T11:18:20.422773Z","steps":["trace[1504706467] 'process raft request' (duration: 247.398304ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:20.422846Z","caller":"traceutil/trace.go:171","msg":"trace[2047127168] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"313.906667ms","start":"2026-06-16T11:18:20.108925Z","end":"2026-06-16T11:18:20.422832Z","steps":["trace[2047127168] 'process raft request' (duration: 313.624502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:20.422939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:20.108895Z","time spent":"313.979288ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:20.423289Z","caller":"traceutil/trace.go:171","msg":"trace[763177480] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"246.908124ms","start":"2026-06-16T11:18:20.176354Z","end":"2026-06-16T11:18:20.423262Z","steps":["trace[763177480] 'process raft request' (duration: 246.384425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:20.70123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.007648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T11:18:20.701294Z","caller":"traceutil/trace.go:171","msg":"trace[681917431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26186; }","duration":"118.134401ms","start":"2026-06-16T11:18:20.583147Z","end":"2026-06-16T11:18:20.701281Z","steps":["trace[681917431] 'range keys from in-memory index tree' (duration: 117.843655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:20.701549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.383423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T11:18:20.701585Z","caller":"traceutil/trace.go:171","msg":"trace[1070315236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26186; }","duration":"144.449253ms","start":"2026-06-16T11:18:20.557129Z","end":"2026-06-16T11:18:20.701578Z","steps":["trace[1070315236] 'range keys from in-memory index tree' (duration: 144.291781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:20.70175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.951325ms","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-16T11:18:20.701829Z","caller":"traceutil/trace.go:171","msg":"trace[739887019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26186; }","duration":"144.040277ms","start":"2026-06-16T11:18:20.557769Z","end":"2026-06-16T11:18:20.701809Z","steps":["trace[739887019] 'range keys from in-memory index tree' (duration: 143.837043ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:20.82506Z","caller":"traceutil/trace.go:171","msg":"trace[934098486] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"120.036057ms","start":"2026-06-16T11:18:20.705001Z","end":"2026-06-16T11:18:20.825037Z","steps":["trace[934098486] 'process raft request' (duration: 119.908034ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:21.128961Z","caller":"traceutil/trace.go:171","msg":"trace[1647211698] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27053; }","duration":"208.322316ms","start":"2026-06-16T11:18:20.920617Z","end":"2026-06-16T11:18:21.128939Z","steps":["trace[1647211698] 'read index received' (duration: 206.334598ms)","trace[1647211698] 'applied index is now lower than readState.Index' (duration: 1.986888ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:21.129001Z","caller":"traceutil/trace.go:171","msg":"trace[1912746265] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"423.550775ms","start":"2026-06-16T11:18:20.705397Z","end":"2026-06-16T11:18:21.128948Z","steps":["trace[1912746265] 'process raft request' (duration: 421.527096ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:21.129019Z","caller":"traceutil/trace.go:171","msg":"trace[2008265101] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"423.378462ms","start":"2026-06-16T11:18:20.705602Z","end":"2026-06-16T11:18:21.128981Z","steps":["trace[2008265101] 'process raft request' (duration: 423.245329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:21.129072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.434988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:21.1291Z","caller":"traceutil/trace.go:171","msg":"trace[1218838013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"208.50351ms","start":"2026-06-16T11:18:20.920588Z","end":"2026-06-16T11:18:21.129091Z","steps":["trace[1218838013] 'agreement among raft nodes before linearized reading' (duration: 208.433859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:21.129181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:20.705584Z","time spent":"423.501424ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:21.129383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:20.705385Z","time spent":"423.679037ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:21.139496Z","caller":"traceutil/trace.go:171","msg":"trace[1532478246] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"206.793438ms","start":"2026-06-16T11:18:20.932681Z","end":"2026-06-16T11:18:21.139475Z","steps":["trace[1532478246] 'process raft request' (duration: 206.543563ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:21.139721Z","caller":"traceutil/trace.go:171","msg":"trace[1887056783] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"204.690867ms","start":"2026-06-16T11:18:20.935008Z","end":"2026-06-16T11:18:21.139699Z","steps":["trace[1887056783] 'process raft request' (duration: 204.437352ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:21.140083Z","caller":"traceutil/trace.go:171","msg":"trace[747297105] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"184.020562ms","start":"2026-06-16T11:18:20.95605Z","end":"2026-06-16T11:18:21.140071Z","steps":["trace[747297105] 'process raft request' (duration: 183.703826ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:28.054468Z","caller":"traceutil/trace.go:171","msg":"trace[735780548] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"129.61858ms","start":"2026-06-16T11:18:27.924828Z","end":"2026-06-16T11:18:28.054446Z","steps":["trace[735780548] 'process raft request' (duration: 129.457927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:28.872261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.050259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:28.872402Z","caller":"traceutil/trace.go:171","msg":"trace[896799263] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"418.976177ms","start":"2026-06-16T11:18:28.453395Z","end":"2026-06-16T11:18:28.872372Z","steps":["trace[896799263] 'process raft request' (duration: 161.702904ms)","trace[896799263] 'compare' (duration: 256.57442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:28.873251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.453373Z","time spent":"419.0767ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:28.910711Z","caller":"traceutil/trace.go:171","msg":"trace[1096390468] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"451.52213ms","start":"2026-06-16T11:18:28.45914Z","end":"2026-06-16T11:18:28.910662Z","steps":["trace[1096390468] 'process raft request' (duration: 451.226575ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:28.910785Z","caller":"traceutil/trace.go:171","msg":"trace[1590117050] linearizableReadLoop","detail":"{readStateIndex:27111; appliedIndex:27107; }","duration":"438.352219ms","start":"2026-06-16T11:18:28.472407Z","end":"2026-06-16T11:18:28.91076Z","steps":["trace[1590117050] 'read index received' (duration: 142.707791ms)","trace[1590117050] 'applied index is now lower than readState.Index' (duration: 295.641788ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:28.910851Z","caller":"traceutil/trace.go:171","msg":"trace[1067594670] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"451.564191ms","start":"2026-06-16T11:18:28.45925Z","end":"2026-06-16T11:18:28.910814Z","steps":["trace[1067594670] 'process raft request' (duration: 451.226165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:28.910893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.459101Z","time spent":"451.701134ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:28.910874Z","caller":"traceutil/trace.go:171","msg":"trace[1332706262] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"451.552631ms","start":"2026-06-16T11:18:28.459295Z","end":"2026-06-16T11:18:28.910848Z","steps":["trace[1332706262] 'process raft request' (duration: 451.217895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:28.91102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.604654ms","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-16T11:18:28.911038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.459217Z","time spent":"451.733605ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:28.911061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.286121ms","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":"warn","ts":"2026-06-16T11:18:28.911073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.459285Z","time spent":"451.680093ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:28.911164Z","caller":"traceutil/trace.go:171","msg":"trace[1863396182] 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:26244; }","duration":"317.427624ms","start":"2026-06-16T11:18:28.593714Z","end":"2026-06-16T11:18:28.911141Z","steps":["trace[1863396182] 'agreement among raft nodes before linearized reading' (duration: 317.223711ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:28.911085Z","caller":"traceutil/trace.go:171","msg":"trace[718432665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26244; }","duration":"438.671675ms","start":"2026-06-16T11:18:28.472396Z","end":"2026-06-16T11:18:28.911068Z","steps":["trace[718432665] 'agreement among raft nodes before linearized reading' (duration: 438.482761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:28.911207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.709003ms","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-16T11:18:28.911342Z","caller":"traceutil/trace.go:171","msg":"trace[272844811] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26244; }","duration":"174.859266ms","start":"2026-06-16T11:18:28.736421Z","end":"2026-06-16T11:18:28.91128Z","steps":["trace[272844811] 'agreement among raft nodes before linearized reading' (duration: 174.694663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:28.911218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.593695Z","time spent":"317.507706ms","remote":"127.0.0.1:50578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-16T11:18:28.911398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.472382Z","time spent":"438.921629ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T11:18:29.260393Z","caller":"traceutil/trace.go:171","msg":"trace[1782996151] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"344.245288ms","start":"2026-06-16T11:18:28.916095Z","end":"2026-06-16T11:18:29.26034Z","steps":["trace[1782996151] 'read index received' (duration: 245.833635ms)","trace[1782996151] 'applied index is now lower than readState.Index' (duration: 98.409693ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:29.260514Z","caller":"traceutil/trace.go:171","msg":"trace[1138504803] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"344.411491ms","start":"2026-06-16T11:18:28.916034Z","end":"2026-06-16T11:18:29.260445Z","steps":["trace[1138504803] 'process raft request' (duration: 245.900266ms)","trace[1138504803] 'compare' (duration: 98.167059ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:29.260676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.528153ms","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-16T11:18:29.260733Z","caller":"traceutil/trace.go:171","msg":"trace[763041816] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26245; }","duration":"344.653745ms","start":"2026-06-16T11:18:28.916061Z","end":"2026-06-16T11:18:29.260715Z","steps":["trace[763041816] 'agreement among raft nodes before linearized reading' (duration: 344.507102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.26072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.916007Z","time spent":"344.597704ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:29.26078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.916044Z","time spent":"344.724117ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-06-16T11:18:29.491855Z","caller":"traceutil/trace.go:171","msg":"trace[1643953804] linearizableReadLoop","detail":"{readStateIndex:27115; appliedIndex:27112; }","duration":"231.329557ms","start":"2026-06-16T11:18:29.260508Z","end":"2026-06-16T11:18:29.491838Z","steps":["trace[1643953804] 'read index received' (duration: 202.052157ms)","trace[1643953804] 'applied index is now lower than readState.Index' (duration: 29.27671ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:29.491904Z","caller":"traceutil/trace.go:171","msg":"trace[787016924] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"320.310299ms","start":"2026-06-16T11:18:29.171573Z","end":"2026-06-16T11:18:29.491883Z","steps":["trace[787016924] 'process raft request' (duration: 320.193907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.492073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.339246ms","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-16T11:18:29.492121Z","caller":"traceutil/trace.go:171","msg":"trace[1610257365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26248; }","duration":"325.424408ms","start":"2026-06-16T11:18:29.166683Z","end":"2026-06-16T11:18:29.492107Z","steps":["trace[1610257365] 'agreement among raft nodes before linearized reading' (duration: 325.265485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.492158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.166662Z","time spent":"325.486298ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T11:18:29.492313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.382015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T11:18:29.492311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.171544Z","time spent":"320.405741ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:29.492458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.445413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-16T11:18:29.492486Z","caller":"traceutil/trace.go:171","msg":"trace[1613785272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26248; }","duration":"226.524555ms","start":"2026-06-16T11:18:29.265956Z","end":"2026-06-16T11:18:29.49248Z","steps":["trace[1613785272] 'agreement among raft nodes before linearized reading' (duration: 226.395242ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:29.492333Z","caller":"traceutil/trace.go:171","msg":"trace[487696230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26248; }","duration":"570.427297ms","start":"2026-06-16T11:18:28.9219Z","end":"2026-06-16T11:18:29.492327Z","steps":["trace[487696230] 'agreement among raft nodes before linearized reading' (duration: 570.389345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.492663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.617807ms","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":"info","ts":"2026-06-16T11:18:29.492726Z","caller":"traceutil/trace.go:171","msg":"trace[1674123998] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26248; }","duration":"226.692188ms","start":"2026-06-16T11:18:29.266015Z","end":"2026-06-16T11:18:29.492707Z","steps":["trace[1674123998] 'agreement among raft nodes before linearized reading' (duration: 226.605896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.492724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.884457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-16T11:18:29.492735Z","caller":"traceutil/trace.go:171","msg":"trace[37776574] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"320.785389ms","start":"2026-06-16T11:18:29.171935Z","end":"2026-06-16T11:18:29.49272Z","steps":["trace[37776574] 'process raft request' (duration: 319.864521ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:29.49272Z","caller":"traceutil/trace.go:171","msg":"trace[558037140] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"576.197136ms","start":"2026-06-16T11:18:28.916485Z","end":"2026-06-16T11:18:29.492682Z","steps":["trace[558037140] 'process raft request' (duration: 546.059189ms)","trace[558037140] 'compare' (duration: 29.126537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:29.492738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.92189Z","time spent":"570.816644ms","remote":"127.0.0.1:46442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T11:18:29.492773Z","caller":"traceutil/trace.go:171","msg":"trace[1317797493] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:26248; }","duration":"186.971088ms","start":"2026-06-16T11:18:29.305787Z","end":"2026-06-16T11:18:29.492758Z","steps":["trace[1317797493] 'agreement among raft nodes before linearized reading' (duration: 186.837165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.492809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.171923Z","time spent":"320.84665ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:29.4929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:28.916475Z","time spent":"576.32854ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T11:18:29.492367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.26245ms","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-16T11:18:29.493077Z","caller":"traceutil/trace.go:171","msg":"trace[70989709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26248; }","duration":"320.032105ms","start":"2026-06-16T11:18:29.17303Z","end":"2026-06-16T11:18:29.493062Z","steps":["trace[70989709] 'agreement among raft nodes before linearized reading' (duration: 318.844101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:29.493194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.172977Z","time spent":"320.202388ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T11:18:29.785094Z","caller":"traceutil/trace.go:171","msg":"trace[1944701699] linearizableReadLoop","detail":"{readStateIndex:27116; appliedIndex:27115; }","duration":"288.97324ms","start":"2026-06-16T11:18:29.496084Z","end":"2026-06-16T11:18:29.785057Z","steps":["trace[1944701699] 'read index received' (duration: 288.612613ms)","trace[1944701699] 'applied index is now lower than readState.Index' (duration: 358.667µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:29.785361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.251635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-16T11:18:29.785442Z","caller":"traceutil/trace.go:171","msg":"trace[1193911698] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26248; }","duration":"289.408258ms","start":"2026-06-16T11:18:29.496017Z","end":"2026-06-16T11:18:29.785425Z","steps":["trace[1193911698] 'agreement among raft nodes before linearized reading' (duration: 289.141643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.535515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:30.381284Z","caller":"traceutil/trace.go:171","msg":"trace[2060669901] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"884.275502ms","start":"2026-06-16T11:18:29.496959Z","end":"2026-06-16T11:18:30.381235Z","steps":["trace[2060669901] 'process raft request' (duration: 587.028304ms)","trace[2060669901] 'compare' (duration: 296.298049ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:30.381369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.496934Z","time spent":"884.399065ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:30.381505Z","caller":"traceutil/trace.go:171","msg":"trace[1945708860] linearizableReadLoop","detail":"{readStateIndex:27119; appliedIndex:27116; }","duration":"596.165809ms","start":"2026-06-16T11:18:29.785325Z","end":"2026-06-16T11:18:30.381491Z","steps":["trace[1945708860] 'read index received' (duration: 298.731217ms)","trace[1945708860] 'applied index is now lower than readState.Index' (duration: 297.433702ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:30.381581Z","caller":"traceutil/trace.go:171","msg":"trace[774391377] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"655.026055ms","start":"2026-06-16T11:18:29.726522Z","end":"2026-06-16T11:18:30.381548Z","steps":["trace[774391377] 'process raft request' (duration: 654.904083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.074349ms","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-16T11:18:30.381695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.462275ms","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-16T11:18:30.381703Z","caller":"traceutil/trace.go:171","msg":"trace[971336701] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26251; }","duration":"883.12186ms","start":"2026-06-16T11:18:29.498571Z","end":"2026-06-16T11:18:30.381693Z","steps":["trace[971336701] 'agreement among raft nodes before linearized reading' (duration: 883.072749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.498563Z","time spent":"883.15662ms","remote":"127.0.0.1:46710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-16T11:18:30.381697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.050238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-06-16T11:18:30.381725Z","caller":"traceutil/trace.go:171","msg":"trace[654703562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26251; }","duration":"630.538706ms","start":"2026-06-16T11:18:29.751176Z","end":"2026-06-16T11:18:30.381715Z","steps":["trace[654703562] 'agreement among raft nodes before linearized reading' (duration: 630.389123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.7265Z","time spent":"655.152667ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:30.381768Z","caller":"traceutil/trace.go:171","msg":"trace[1320554975] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26251; }","duration":"605.157471ms","start":"2026-06-16T11:18:29.77659Z","end":"2026-06-16T11:18:30.381748Z","steps":["trace[1320554975] 'agreement among raft nodes before linearized reading' (duration: 604.955277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.751157Z","time spent":"630.610747ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T11:18:30.381806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.776569Z","time spent":"605.228072ms","remote":"127.0.0.1:46580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-06-16T11:18:30.381901Z","caller":"traceutil/trace.go:171","msg":"trace[1485421221] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"883.553918ms","start":"2026-06-16T11:18:29.498339Z","end":"2026-06-16T11:18:30.381893Z","steps":["trace[1485421221] 'process raft request' (duration: 882.825494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.381951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.49832Z","time spent":"883.608969ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:30.383163Z","caller":"traceutil/trace.go:171","msg":"trace[2094672954] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"323.100744ms","start":"2026-06-16T11:18:30.060055Z","end":"2026-06-16T11:18:30.383156Z","steps":["trace[2094672954] 'process raft request' (duration: 323.069283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.383209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:30.06003Z","time spent":"323.156375ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:30.383193Z","caller":"traceutil/trace.go:171","msg":"trace[1578400949] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"597.347832ms","start":"2026-06-16T11:18:29.785816Z","end":"2026-06-16T11:18:30.383164Z","steps":["trace[1578400949] 'process raft request' (duration: 597.2688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.383343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.785799Z","time spent":"597.446484ms","remote":"127.0.0.1:46454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:30.433499Z","caller":"traceutil/trace.go:171","msg":"trace[1608626715] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"101.92452ms","start":"2026-06-16T11:18:30.331541Z","end":"2026-06-16T11:18:30.433466Z","steps":["trace[1608626715] 'process raft request' (duration: 101.676515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.433578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.805637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-06-16T11:18:30.433639Z","caller":"traceutil/trace.go:171","msg":"trace[895665610] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26254; }","duration":"646.92315ms","start":"2026-06-16T11:18:29.7867Z","end":"2026-06-16T11:18:30.433623Z","steps":["trace[895665610] 'agreement among raft nodes before linearized reading' (duration: 646.693675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:30.433677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:29.786677Z","time spent":"646.992891ms","remote":"127.0.0.1:46596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18376,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-16T11:18:35.867497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.655876ms","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-16T11:18:35.867589Z","caller":"traceutil/trace.go:171","msg":"trace[1554517320] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26351; }","duration":"216.810679ms","start":"2026-06-16T11:18:35.650758Z","end":"2026-06-16T11:18:35.867569Z","steps":["trace[1554517320] 'range keys from in-memory index tree' (duration: 216.571394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:35.867757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.498223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4559152e-cd03-4373-95e8-d3a56501afb5\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-16T11:18:35.867845Z","caller":"traceutil/trace.go:171","msg":"trace[591868696] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4559152e-cd03-4373-95e8-d3a56501afb5; range_end:; response_count:1; response_revision:26351; }","duration":"146.633216ms","start":"2026-06-16T11:18:35.721189Z","end":"2026-06-16T11:18:35.867822Z","steps":["trace[591868696] 'range keys from in-memory index tree' (duration: 146.33047ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:36.049916Z","caller":"traceutil/trace.go:171","msg":"trace[77526755] linearizableReadLoop","detail":"{readStateIndex:27224; appliedIndex:27223; }","duration":"169.34268ms","start":"2026-06-16T11:18:35.880535Z","end":"2026-06-16T11:18:36.049878Z","steps":["trace[77526755] 'read index received' (duration: 169.006083ms)","trace[77526755] 'applied index is now lower than readState.Index' (duration: 334.837µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:36.050468Z","caller":"traceutil/trace.go:171","msg":"trace[1127482609] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"175.467009ms","start":"2026-06-16T11:18:35.874935Z","end":"2026-06-16T11:18:36.050402Z","steps":["trace[1127482609] 'process raft request' (duration: 174.716803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.050651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.44161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:36.050705Z","caller":"traceutil/trace.go:171","msg":"trace[716469739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26353; }","duration":"127.553951ms","start":"2026-06-16T11:18:35.923136Z","end":"2026-06-16T11:18:36.05069Z","steps":["trace[716469739] 'agreement among raft nodes before linearized reading' (duration: 127.429819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.050948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.820372ms","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-16T11:18:36.050987Z","caller":"traceutil/trace.go:171","msg":"trace[2032048847] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26353; }","duration":"178.881323ms","start":"2026-06-16T11:18:35.872094Z","end":"2026-06-16T11:18:36.050976Z","steps":["trace[2032048847] 'agreement among raft nodes before linearized reading' (duration: 178.821202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.328354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.633236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:18:36.328556Z","caller":"traceutil/trace.go:171","msg":"trace[481999960] linearizableReadLoop","detail":"{readStateIndex:27225; appliedIndex:27224; }","duration":"265.827227ms","start":"2026-06-16T11:18:36.062656Z","end":"2026-06-16T11:18:36.328483Z","steps":["trace[481999960] 'read index received' (duration: 119.838403ms)","trace[481999960] 'applied index is now lower than readState.Index' (duration: 145.986414ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:36.328613Z","caller":"traceutil/trace.go:171","msg":"trace[1759194304] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"267.335087ms","start":"2026-06-16T11:18:36.06124Z","end":"2026-06-16T11:18:36.328575Z","steps":["trace[1759194304] 'process raft request' (duration: 121.277112ms)","trace[1759194304] 'compare' (duration: 144.890732ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:36.328788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.109322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-16T11:18:36.328833Z","caller":"traceutil/trace.go:171","msg":"trace[1514108019] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26354; }","duration":"266.182604ms","start":"2026-06-16T11:18:36.062637Z","end":"2026-06-16T11:18:36.32882Z","steps":["trace[1514108019] 'agreement among raft nodes before linearized reading' (duration: 266.021491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.329128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.474777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-16T11:18:36.32945Z","caller":"traceutil/trace.go:171","msg":"trace[619497042] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26354; }","duration":"212.821683ms","start":"2026-06-16T11:18:36.116602Z","end":"2026-06-16T11:18:36.329424Z","steps":["trace[619497042] 'agreement among raft nodes before linearized reading' (duration: 212.14244ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:36.630926Z","caller":"traceutil/trace.go:171","msg":"trace[1371661534] linearizableReadLoop","detail":"{readStateIndex:27226; appliedIndex:27225; }","duration":"219.254567ms","start":"2026-06-16T11:18:36.411647Z","end":"2026-06-16T11:18:36.630901Z","steps":["trace[1371661534] 'read index received' (duration: 219.177206ms)","trace[1371661534] 'applied index is now lower than readState.Index' (duration: 74.051µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:36.63104Z","caller":"traceutil/trace.go:171","msg":"trace[41250023] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"226.985274ms","start":"2026-06-16T11:18:36.404019Z","end":"2026-06-16T11:18:36.631005Z","steps":["trace[41250023] 'process raft request' (duration: 226.690588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.631158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.489851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T11:18:36.631211Z","caller":"traceutil/trace.go:171","msg":"trace[564155033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26355; }","duration":"219.588823ms","start":"2026-06-16T11:18:36.411607Z","end":"2026-06-16T11:18:36.631196Z","steps":["trace[564155033] 'agreement among raft nodes before linearized reading' (duration: 219.418159ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:36.652027Z","caller":"traceutil/trace.go:171","msg":"trace[53036764] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"199.366966ms","start":"2026-06-16T11:18:36.45264Z","end":"2026-06-16T11:18:36.652007Z","steps":["trace[53036764] 'process raft request' (duration: 199.0564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.6521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.27419ms","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-16T11:18:36.652154Z","caller":"traceutil/trace.go:171","msg":"trace[1279572037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26356; }","duration":"238.373072ms","start":"2026-06-16T11:18:36.413766Z","end":"2026-06-16T11:18:36.652139Z","steps":["trace[1279572037] 'agreement among raft nodes before linearized reading' (duration: 238.178638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:36.652317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.308594ms","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-16T11:18:36.652398Z","caller":"traceutil/trace.go:171","msg":"trace[1027249757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26356; }","duration":"165.438076ms","start":"2026-06-16T11:18:36.486943Z","end":"2026-06-16T11:18:36.652381Z","steps":["trace[1027249757] 'agreement among raft nodes before linearized reading' (duration: 165.215111ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:36.781585Z","caller":"traceutil/trace.go:171","msg":"trace[219203522] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"144.916983ms","start":"2026-06-16T11:18:36.636641Z","end":"2026-06-16T11:18:36.781558Z","steps":["trace[219203522] 'process raft request' (duration: 117.05486ms)","trace[219203522] 'compare' (duration: 27.545116ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:36.781982Z","caller":"traceutil/trace.go:171","msg":"trace[145609317] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"124.755338ms","start":"2026-06-16T11:18:36.65719Z","end":"2026-06-16T11:18:36.781945Z","steps":["trace[145609317] 'process raft request' (duration: 124.32831ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:36.782083Z","caller":"traceutil/trace.go:171","msg":"trace[1615616871] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"124.86368ms","start":"2026-06-16T11:18:36.657206Z","end":"2026-06-16T11:18:36.78207Z","steps":["trace[1615616871] 'process raft request' (duration: 124.642486ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:39.010679Z","caller":"traceutil/trace.go:171","msg":"trace[874375179] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"107.515767ms","start":"2026-06-16T11:18:38.903123Z","end":"2026-06-16T11:18:39.010639Z","steps":["trace[874375179] 'process raft request' (duration: 107.271332ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:39.247117Z","caller":"traceutil/trace.go:171","msg":"trace[570003605] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"303.983897ms","start":"2026-06-16T11:18:38.943112Z","end":"2026-06-16T11:18:39.247095Z","steps":["trace[570003605] 'process raft request' (duration: 303.801494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:39.247263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:38.943089Z","time spent":"304.09868ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:39.247505Z","caller":"traceutil/trace.go:171","msg":"trace[205762588] linearizableReadLoop","detail":"{readStateIndex:27249; appliedIndex:27246; }","duration":"235.64495ms","start":"2026-06-16T11:18:39.011843Z","end":"2026-06-16T11:18:39.247488Z","steps":["trace[205762588] 'read index received' (duration: 234.964657ms)","trace[205762588] 'applied index is now lower than readState.Index' (duration: 679.243µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:39.247555Z","caller":"traceutil/trace.go:171","msg":"trace[465030658] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"304.129331ms","start":"2026-06-16T11:18:38.943416Z","end":"2026-06-16T11:18:39.247546Z","steps":["trace[465030658] 'process raft request' (duration: 303.640422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:39.247598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:38.943409Z","time spent":"304.166581ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:39.247627Z","caller":"traceutil/trace.go:171","msg":"trace[163493073] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"301.529501ms","start":"2026-06-16T11:18:38.946055Z","end":"2026-06-16T11:18:39.247585Z","steps":["trace[163493073] 'process raft request' (duration: 301.216655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:39.247684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.806103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:39.247746Z","caller":"traceutil/trace.go:171","msg":"trace[324989655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26378; }","duration":"235.926154ms","start":"2026-06-16T11:18:39.011808Z","end":"2026-06-16T11:18:39.247734Z","steps":["trace[324989655] 'agreement among raft nodes before linearized reading' (duration: 235.808412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:39.247781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:38.946031Z","time spent":"301.678013ms","remote":"127.0.0.1:46696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:44.016531Z","caller":"traceutil/trace.go:171","msg":"trace[1240105975] linearizableReadLoop","detail":"{readStateIndex:27305; appliedIndex:27304; }","duration":"128.782774ms","start":"2026-06-16T11:18:43.887719Z","end":"2026-06-16T11:18:44.016501Z","steps":["trace[1240105975] 'read index received' (duration: 128.706473ms)","trace[1240105975] 'applied index is now lower than readState.Index' (duration: 75.181µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:44.01691Z","caller":"traceutil/trace.go:171","msg":"trace[1475003216] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"134.892441ms","start":"2026-06-16T11:18:43.882001Z","end":"2026-06-16T11:18:44.016894Z","steps":["trace[1475003216] 'process raft request' (duration: 134.32842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.017298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.559409ms","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-16T11:18:44.017356Z","caller":"traceutil/trace.go:171","msg":"trace[77115383] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26433; }","duration":"129.62681ms","start":"2026-06-16T11:18:43.887713Z","end":"2026-06-16T11:18:44.01734Z","steps":["trace[77115383] 'agreement among raft nodes before linearized reading' (duration: 129.418766ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:44.132503Z","caller":"traceutil/trace.go:171","msg":"trace[231653260] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"107.754503ms","start":"2026-06-16T11:18:44.024728Z","end":"2026-06-16T11:18:44.132482Z","steps":["trace[231653260] 'process raft request' (duration: 77.052555ms)","trace[231653260] 'compare' (duration: 30.602906ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:44.393249Z","caller":"traceutil/trace.go:171","msg":"trace[1742111780] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"368.356559ms","start":"2026-06-16T11:18:44.024869Z","end":"2026-06-16T11:18:44.393225Z","steps":["trace[1742111780] 'process raft request' (duration: 367.818688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.393462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:44.024836Z","time spent":"368.556973ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:44.393529Z","caller":"traceutil/trace.go:171","msg":"trace[349518995] linearizableReadLoop","detail":"{readStateIndex:27309; appliedIndex:27305; }","duration":"348.07866ms","start":"2026-06-16T11:18:44.045423Z","end":"2026-06-16T11:18:44.393502Z","steps":["trace[349518995] 'read index received' (duration: 56.372118ms)","trace[349518995] 'applied index is now lower than readState.Index' (duration: 291.705542ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:44.393682Z","caller":"traceutil/trace.go:171","msg":"trace[1034336132] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"368.607934ms","start":"2026-06-16T11:18:44.025054Z","end":"2026-06-16T11:18:44.393662Z","steps":["trace[1034336132] 'process raft request' (duration: 368.35783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.393697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.836986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:44.393676Z","caller":"traceutil/trace.go:171","msg":"trace[1465754823] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"368.693106ms","start":"2026-06-16T11:18:44.024941Z","end":"2026-06-16T11:18:44.393634Z","steps":["trace[1465754823] 'process raft request' (duration: 368.218147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.393741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:44.025048Z","time spent":"368.666485ms","remote":"127.0.0.1:46710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:44.393737Z","caller":"traceutil/trace.go:171","msg":"trace[924894729] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26437; }","duration":"302.929648ms","start":"2026-06-16T11:18:44.090793Z","end":"2026-06-16T11:18:44.393723Z","steps":["trace[924894729] 'agreement among raft nodes before linearized reading' (duration: 302.854936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.393749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.308645ms","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":"warn","ts":"2026-06-16T11:18:44.393779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:44.090771Z","time spent":"302.998468ms","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true "} {"level":"info","ts":"2026-06-16T11:18:44.393826Z","caller":"traceutil/trace.go:171","msg":"trace[1440367564] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26437; }","duration":"348.441779ms","start":"2026-06-16T11:18:44.045362Z","end":"2026-06-16T11:18:44.393804Z","steps":["trace[1440367564] 'agreement among raft nodes before linearized reading' (duration: 348.294146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.393869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:44.045335Z","time spent":"348.52641ms","remote":"127.0.0.1:54186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-06-16T11:18:44.393869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T11:18:44.024921Z","time spent":"368.846388ms","remote":"127.0.0.1:46710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T11:18:44.842451Z","caller":"traceutil/trace.go:171","msg":"trace[1274464575] linearizableReadLoop","detail":"{readStateIndex:27311; appliedIndex:27309; }","duration":"137.625043ms","start":"2026-06-16T11:18:44.7048Z","end":"2026-06-16T11:18:44.842425Z","steps":["trace[1274464575] 'read index received' (duration: 18.047104ms)","trace[1274464575] 'applied index is now lower than readState.Index' (duration: 119.576649ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:44.842526Z","caller":"traceutil/trace.go:171","msg":"trace[990341661] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"168.413273ms","start":"2026-06-16T11:18:44.674072Z","end":"2026-06-16T11:18:44.842485Z","steps":["trace[990341661] 'process raft request' (duration: 168.187669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.84263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.795567ms","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-16T11:18:44.842677Z","caller":"traceutil/trace.go:171","msg":"trace[2081480361] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26439; }","duration":"137.892408ms","start":"2026-06-16T11:18:44.704771Z","end":"2026-06-16T11:18:44.842663Z","steps":["trace[2081480361] 'agreement among raft nodes before linearized reading' (duration: 137.795496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:18:44.843076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.698769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:44.843152Z","caller":"traceutil/trace.go:171","msg":"trace[578011554] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26439; }","duration":"120.817982ms","start":"2026-06-16T11:18:44.722313Z","end":"2026-06-16T11:18:44.843131Z","steps":["trace[578011554] 'agreement among raft nodes before linearized reading' (duration: 120.356333ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:53.021417Z","caller":"traceutil/trace.go:171","msg":"trace[33220137] linearizableReadLoop","detail":"{readStateIndex:27379; appliedIndex:27378; }","duration":"100.301829ms","start":"2026-06-16T11:18:52.921087Z","end":"2026-06-16T11:18:53.021389Z","steps":["trace[33220137] 'read index received' (duration: 54.665945ms)","trace[33220137] 'applied index is now lower than readState.Index' (duration: 45.634444ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:53.021483Z","caller":"traceutil/trace.go:171","msg":"trace[1171372081] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"130.305274ms","start":"2026-06-16T11:18:52.89114Z","end":"2026-06-16T11:18:53.021446Z","steps":["trace[1171372081] 'process raft request' (duration: 84.598149ms)","trace[1171372081] 'compare' (duration: 45.500581ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:53.021537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.438193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:18:53.021597Z","caller":"traceutil/trace.go:171","msg":"trace[1788627674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26505; }","duration":"100.507784ms","start":"2026-06-16T11:18:52.921073Z","end":"2026-06-16T11:18:53.021581Z","steps":["trace[1788627674] 'agreement among raft nodes before linearized reading' (duration: 100.409222ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:53.153607Z","caller":"traceutil/trace.go:171","msg":"trace[2073352783] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"127.713735ms","start":"2026-06-16T11:18:53.025869Z","end":"2026-06-16T11:18:53.153582Z","steps":["trace[2073352783] 'process raft request' (duration: 127.526601ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:53.153816Z","caller":"traceutil/trace.go:171","msg":"trace[1971793874] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"127.342378ms","start":"2026-06-16T11:18:53.026435Z","end":"2026-06-16T11:18:53.153777Z","steps":["trace[1971793874] 'process raft request' (duration: 127.107883ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:55.765262Z","caller":"traceutil/trace.go:171","msg":"trace[1664922366] linearizableReadLoop","detail":"{readStateIndex:27404; appliedIndex:27403; }","duration":"155.835233ms","start":"2026-06-16T11:18:55.609392Z","end":"2026-06-16T11:18:55.765227Z","steps":["trace[1664922366] 'read index received' (duration: 60.446827ms)","trace[1664922366] 'applied index is now lower than readState.Index' (duration: 95.386726ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:18:55.765515Z","caller":"traceutil/trace.go:171","msg":"trace[1039174255] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"167.460085ms","start":"2026-06-16T11:18:55.598025Z","end":"2026-06-16T11:18:55.765485Z","steps":["trace[1039174255] 'process raft request' (duration: 71.864415ms)","trace[1039174255] 'compare' (duration: 95.176722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:18:55.765548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.142558ms","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-16T11:18:55.765604Z","caller":"traceutil/trace.go:171","msg":"trace[133920823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26529; }","duration":"156.260701ms","start":"2026-06-16T11:18:55.609328Z","end":"2026-06-16T11:18:55.765589Z","steps":["trace[133920823] 'agreement among raft nodes before linearized reading' (duration: 156.029686ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:18:55.886055Z","caller":"traceutil/trace.go:171","msg":"trace[305969066] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"117.182693ms","start":"2026-06-16T11:18:55.76881Z","end":"2026-06-16T11:18:55.885992Z","steps":["trace[305969066] 'process raft request' (duration: 104.649463ms)","trace[305969066] 'compare' (duration: 12.313486ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:19:01.088399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.796893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T11:19:01.08848Z","caller":"traceutil/trace.go:171","msg":"trace[1902698513] linearizableReadLoop","detail":"{readStateIndex:27440; appliedIndex:27439; }","duration":"167.072577ms","start":"2026-06-16T11:19:00.921394Z","end":"2026-06-16T11:19:01.088467Z","steps":["trace[1902698513] 'read index received' (duration: 24.010469ms)","trace[1902698513] 'applied index is now lower than readState.Index' (duration: 143.061318ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:19:01.088542Z","caller":"traceutil/trace.go:171","msg":"trace[1246516595] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"219.303567ms","start":"2026-06-16T11:19:00.869231Z","end":"2026-06-16T11:19:01.088535Z","steps":["trace[1246516595] 'process raft request' (duration: 76.28958ms)","trace[1246516595] 'compare' (duration: 142.6443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:19:01.088684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.28651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:19:01.088751Z","caller":"traceutil/trace.go:171","msg":"trace[158040762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26564; }","duration":"167.406733ms","start":"2026-06-16T11:19:00.921326Z","end":"2026-06-16T11:19:01.088733Z","steps":["trace[158040762] 'agreement among raft nodes before linearized reading' (duration: 167.28449ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:01.154392Z","caller":"traceutil/trace.go:171","msg":"trace[194165336] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"104.632012ms","start":"2026-06-16T11:19:01.049737Z","end":"2026-06-16T11:19:01.154369Z","steps":["trace[194165336] 'process raft request' (duration: 104.467419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:01.154458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.798416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:19:01.154504Z","caller":"traceutil/trace.go:171","msg":"trace[327281] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26565; }","duration":"127.896407ms","start":"2026-06-16T11:19:01.026595Z","end":"2026-06-16T11:19:01.154491Z","steps":["trace[327281] 'agreement among raft nodes before linearized reading' (duration: 127.777935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:11.32866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.963925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-16T11:19:11.328703Z","caller":"traceutil/trace.go:171","msg":"trace[1142355130] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"140.05417ms","start":"2026-06-16T11:19:11.188617Z","end":"2026-06-16T11:19:11.328671Z","steps":["trace[1142355130] 'process raft request' (duration: 126.95147ms)","trace[1142355130] 'compare' (duration: 12.789715ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:19:11.328742Z","caller":"traceutil/trace.go:171","msg":"trace[1934548434] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26650; }","duration":"145.084846ms","start":"2026-06-16T11:19:11.18364Z","end":"2026-06-16T11:19:11.328725Z","steps":["trace[1934548434] 'range keys from in-memory index tree' (duration: 144.775221ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.467786Z","caller":"traceutil/trace.go:171","msg":"trace[1926483184] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"134.087735ms","start":"2026-06-16T11:19:11.333669Z","end":"2026-06-16T11:19:11.467757Z","steps":["trace[1926483184] 'process raft request' (duration: 133.910552ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.468627Z","caller":"traceutil/trace.go:171","msg":"trace[1678060727] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"112.36241ms","start":"2026-06-16T11:19:11.356243Z","end":"2026-06-16T11:19:11.468605Z","steps":["trace[1678060727] 'process raft request' (duration: 112.145466ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.46867Z","caller":"traceutil/trace.go:171","msg":"trace[1541364154] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"106.331035ms","start":"2026-06-16T11:19:11.362321Z","end":"2026-06-16T11:19:11.468652Z","steps":["trace[1541364154] 'process raft request' (duration: 106.250584ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.468858Z","caller":"traceutil/trace.go:171","msg":"trace[1425018821] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"106.634001ms","start":"2026-06-16T11:19:11.362216Z","end":"2026-06-16T11:19:11.46885Z","steps":["trace[1425018821] 'process raft request' (duration: 106.304555ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.469035Z","caller":"traceutil/trace.go:171","msg":"trace[1519842101] linearizableReadLoop","detail":"{readStateIndex:27533; appliedIndex:27529; }","duration":"102.296548ms","start":"2026-06-16T11:19:11.36673Z","end":"2026-06-16T11:19:11.469027Z","steps":["trace[1519842101] 'read index received' (duration: 100.914091ms)","trace[1519842101] 'applied index is now lower than readState.Index' (duration: 1.381927ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:19:11.469306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.175307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-16T11:19:11.469317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.195897ms","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-16T11:19:11.469365Z","caller":"traceutil/trace.go:171","msg":"trace[149549654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26656; }","duration":"100.263849ms","start":"2026-06-16T11:19:11.369086Z","end":"2026-06-16T11:19:11.469349Z","steps":["trace[149549654] 'agreement among raft nodes before linearized reading' (duration: 100.091476ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:11.469382Z","caller":"traceutil/trace.go:171","msg":"trace[821598051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26656; }","duration":"100.29883ms","start":"2026-06-16T11:19:11.369064Z","end":"2026-06-16T11:19:11.469363Z","steps":["trace[821598051] 'agreement among raft nodes before linearized reading' (duration: 100.101756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:11.469409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.675285ms","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-16T11:19:11.469436Z","caller":"traceutil/trace.go:171","msg":"trace[2032375377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26656; }","duration":"102.729156ms","start":"2026-06-16T11:19:11.366696Z","end":"2026-06-16T11:19:11.469425Z","steps":["trace[2032375377] 'agreement among raft nodes before linearized reading' (duration: 102.41453ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:16.252435Z","caller":"traceutil/trace.go:171","msg":"trace[424859069] transaction","detail":"{read_only:false; response_revision:26691; number_of_response:1; }","duration":"167.426033ms","start":"2026-06-16T11:19:16.084966Z","end":"2026-06-16T11:19:16.252392Z","steps":["trace[424859069] 'process raft request' (duration: 167.188549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:16.581147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.40609ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T11:19:16.581362Z","caller":"traceutil/trace.go:171","msg":"trace[879150564] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26691; }","duration":"229.632084ms","start":"2026-06-16T11:19:16.351709Z","end":"2026-06-16T11:19:16.581341Z","steps":["trace[879150564] 'range keys from in-memory index tree' (duration: 229.387899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:16.899478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.42468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-55c784dfcc-7hh9k.18b98a450b3e561c\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-06-16T11:19:16.899583Z","caller":"traceutil/trace.go:171","msg":"trace[1004102095] range","detail":"{range_begin:/registry/events/openstack/cinder-api-55c784dfcc-7hh9k.18b98a450b3e561c; range_end:; response_count:1; response_revision:26692; }","duration":"148.581924ms","start":"2026-06-16T11:19:16.750973Z","end":"2026-06-16T11:19:16.899555Z","steps":["trace[1004102095] 'range keys from in-memory index tree' (duration: 148.098774ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:17.243035Z","caller":"traceutil/trace.go:171","msg":"trace[248322274] linearizableReadLoop","detail":"{readStateIndex:27573; appliedIndex:27572; }","duration":"191.239999ms","start":"2026-06-16T11:19:17.051768Z","end":"2026-06-16T11:19:17.243008Z","steps":["trace[248322274] 'read index received' (duration: 191.048735ms)","trace[248322274] 'applied index is now lower than readState.Index' (duration: 190.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:19:17.243139Z","caller":"traceutil/trace.go:171","msg":"trace[535015701] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"217.312139ms","start":"2026-06-16T11:19:17.025794Z","end":"2026-06-16T11:19:17.243106Z","steps":["trace[535015701] 'process raft request' (duration: 217.032164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:17.243285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.505515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-16T11:19:17.243468Z","caller":"traceutil/trace.go:171","msg":"trace[1238670919] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26694; }","duration":"191.755289ms","start":"2026-06-16T11:19:17.051695Z","end":"2026-06-16T11:19:17.24345Z","steps":["trace[1238670919] 'agreement among raft nodes before linearized reading' (duration: 191.415303ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:19:22.478766Z","caller":"traceutil/trace.go:171","msg":"trace[1154202948] linearizableReadLoop","detail":"{readStateIndex:27618; appliedIndex:27617; }","duration":"110.364902ms","start":"2026-06-16T11:19:22.368385Z","end":"2026-06-16T11:19:22.47875Z","steps":["trace[1154202948] 'read index received' (duration: 110.26639ms)","trace[1154202948] 'applied index is now lower than readState.Index' (duration: 97.612µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T11:19:22.47883Z","caller":"traceutil/trace.go:171","msg":"trace[1848529050] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"117.129012ms","start":"2026-06-16T11:19:22.361668Z","end":"2026-06-16T11:19:22.478797Z","steps":["trace[1848529050] 'process raft request' (duration: 116.962889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T11:19:22.478938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.537955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-06-16T11:19:22.478963Z","caller":"traceutil/trace.go:171","msg":"trace[1818276867] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26738; }","duration":"110.605887ms","start":"2026-06-16T11:19:22.368349Z","end":"2026-06-16T11:19:22.478955Z","steps":["trace[1818276867] 'agreement among raft nodes before linearized reading' (duration: 110.501825ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:20:07.436864Z","caller":"traceutil/trace.go:171","msg":"trace[276165470] transaction","detail":"{read_only:false; response_revision:27267; number_of_response:1; }","duration":"131.746331ms","start":"2026-06-16T11:20:07.305096Z","end":"2026-06-16T11:20:07.436842Z","steps":["trace[276165470] 'process raft request' (duration: 131.492626ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:20:07.436838Z","caller":"traceutil/trace.go:171","msg":"trace[1225132563] linearizableReadLoop","detail":"{readStateIndex:28158; appliedIndex:28157; }","duration":"108.898374ms","start":"2026-06-16T11:20:07.327905Z","end":"2026-06-16T11:20:07.436803Z","steps":["trace[1225132563] 'read index received' (duration: 108.68376ms)","trace[1225132563] 'applied index is now lower than readState.Index' (duration: 213.214µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T11:20:07.437023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.084127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T11:20:07.437101Z","caller":"traceutil/trace.go:171","msg":"trace[336509293] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:27267; }","duration":"109.22799ms","start":"2026-06-16T11:20:07.327839Z","end":"2026-06-16T11:20:07.437067Z","steps":["trace[336509293] 'agreement among raft nodes before linearized reading' (duration: 109.073017ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:20:07.461917Z","caller":"traceutil/trace.go:171","msg":"trace[295963736] transaction","detail":"{read_only:false; response_revision:27268; number_of_response:1; }","duration":"115.416399ms","start":"2026-06-16T11:20:07.346467Z","end":"2026-06-16T11:20:07.461884Z","steps":["trace[295963736] 'process raft request' (duration: 114.95866ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T11:21:16.051515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25033} {"level":"info","ts":"2026-06-16T11:21:16.196387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25033,"took":"141.327271ms","hash":1558431788,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":36859904,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-16T11:21:16.196486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1558431788,"revision":25033,"compact-revision":21949} {"level":"info","ts":"2026-06-16T11:23:12.931032Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T11:23:12.93611Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-16T11:23:12.936216Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-16T11:26:16.060355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27876} {"level":"info","ts":"2026-06-16T11:26:16.208405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27876,"took":"142.777219ms","hash":2501802476,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T11:26:16.20847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2501802476,"revision":27876,"compact-revision":25033} {"level":"info","ts":"2026-06-16T11:31:16.068357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30492} {"level":"info","ts":"2026-06-16T11:31:16.219504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30492,"took":"146.560073ms","hash":2843487837,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":30625792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T11:31:16.219614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843487837,"revision":30492,"compact-revision":27876} {"level":"info","ts":"2026-06-16T11:36:16.081154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32745} {"level":"info","ts":"2026-06-16T11:36:16.235745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32745,"took":"151.708901ms","hash":3905932631,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-16T11:36:16.235823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3905932631,"revision":32745,"compact-revision":30492} {"level":"info","ts":"2026-06-16T11:41:16.08696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34981} {"level":"info","ts":"2026-06-16T11:41:16.233346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34981,"took":"142.775431ms","hash":273951372,"current-db-size-bytes":61452288,"current-db-size":"62 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T11:41:16.233399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":273951372,"revision":34981,"compact-revision":32745} {"level":"info","ts":"2026-06-16T11:44:37.125465Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T11:44:37.1297Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-16T11:44:37.129824Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-16T11:46:16.094814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37274} {"level":"info","ts":"2026-06-16T11:46:16.226671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37274,"took":"127.770455ms","hash":2915083961,"current-db-size-bytes":62300160,"current-db-size":"62 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T11:46:16.226767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915083961,"revision":37274,"compact-revision":34981} {"level":"info","ts":"2026-06-16T11:51:16.103448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39558} {"level":"info","ts":"2026-06-16T11:51:16.226818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39558,"took":"119.459197ms","hash":663315994,"current-db-size-bytes":62300160,"current-db-size":"62 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T11:51:16.226871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":663315994,"revision":39558,"compact-revision":37274}