{"level":"warn","ts":"2026-06-15T18:58:18.99778Z","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-15T18:58:18.99787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237: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.19.213.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237: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-15T18:58:18.997943Z","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-15T18:58:18.997957Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-06-15T18:58:18.997974Z","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-15T18:58:18.998584Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-06-15T18:58:18.998673Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237: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-15T18:58:19.003176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.259762ms"} {"level":"info","ts":"2026-06-15T18:58:19.010912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-06-15T18:58:19.011144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T18:58:19.011357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-06-15T18:58:19.011377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T18:58:19.011389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-06-15T18:58:19.011731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-06-15T18:58:19.01777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T18:58:19.018954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T18:58:19.020257Z","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-15T18:58:19.022208Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T18:58:19.022371Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T18:58:19.023206Z","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-15T18:58:19.023314Z","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-15T18:58:19.023339Z","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-15T18:58:19.028496Z","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-15T18:58:19.028932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-06-15T18:58:19.029102Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-06-15T18:58:19.029348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-06-15T18:58:19.029377Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-06-15T18:58:19.029723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T18:58:19.02978Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T18:58:19.612918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T18:58:19.612992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T18:58:19.613044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-06-15T18:58:19.613094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-06-15T18:58:19.613107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-06-15T18:58:19.613129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-06-15T18:58:19.613148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-06-15T18:58:19.614001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T18:58:19.614707Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T18:58:19.614839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T18:58:19.614934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T18:58:19.615201Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T18:58:19.615279Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T18:58:19.615321Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T18:58:19.615564Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T18:58:19.615593Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T18:58:19.617865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T18:58:19.621441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"info","ts":"2026-06-15T18:58:30.258486Z","caller":"traceutil/trace.go:171","msg":"trace[970929198] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"346.943904ms","start":"2026-06-15T18:58:29.911508Z","end":"2026-06-15T18:58:30.258452Z","steps":["trace[970929198] 'process raft request' (duration: 346.765053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:30.258675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.987907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-15T18:58:30.258823Z","caller":"traceutil/trace.go:171","msg":"trace[1370838274] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"306.23078ms","start":"2026-06-15T18:58:29.952574Z","end":"2026-06-15T18:58:30.258804Z","steps":["trace[1370838274] 'agreement among raft nodes before linearized reading' (duration: 305.965498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:30.258872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:29.952501Z","time spent":"306.35975ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-06-15T18:58:30.258426Z","caller":"traceutil/trace.go:171","msg":"trace[1849441999] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"305.754696ms","start":"2026-06-15T18:58:29.952649Z","end":"2026-06-15T18:58:30.258404Z","steps":["trace[1849441999] 'read index received' (duration: 305.595035ms)","trace[1849441999] 'applied index is now lower than readState.Index' (duration: 158.251µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T18:58:30.259539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.054533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-15T18:58:30.259597Z","caller":"traceutil/trace.go:171","msg":"trace[410194708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"157.176853ms","start":"2026-06-15T18:58:30.102405Z","end":"2026-06-15T18:58:30.259582Z","steps":["trace[410194708] 'agreement among raft nodes before linearized reading' (duration: 157.086332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:30.259655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.595276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-06-15T18:58:30.259729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.975661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-15T18:58:30.25974Z","caller":"traceutil/trace.go:171","msg":"trace[131635975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"207.736358ms","start":"2026-06-15T18:58:30.05198Z","end":"2026-06-15T18:58:30.259716Z","steps":["trace[131635975] 'agreement among raft nodes before linearized reading' (duration: 207.545707ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:58:30.25978Z","caller":"traceutil/trace.go:171","msg":"trace[2134695012] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"107.063321ms","start":"2026-06-15T18:58:30.152699Z","end":"2026-06-15T18:58:30.259763Z","steps":["trace[2134695012] 'agreement among raft nodes before linearized reading' (duration: 106.990771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:30.259894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:29.911475Z","time spent":"347.109165ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T18:58:30.259923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.850682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-06-15T18:58:30.259968Z","caller":"traceutil/trace.go:171","msg":"trace[781748542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"256.959903ms","start":"2026-06-15T18:58:30.002991Z","end":"2026-06-15T18:58:30.259951Z","steps":["trace[781748542] 'agreement among raft nodes before linearized reading' (duration: 256.401868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:35.88251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.501975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:58:35.882597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.10863ms","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-15T18:58:35.882616Z","caller":"traceutil/trace.go:171","msg":"trace[342381819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"236.658931ms","start":"2026-06-15T18:58:35.645934Z","end":"2026-06-15T18:58:35.882593Z","steps":["trace[342381819] 'range keys from in-memory index tree' (duration: 236.356688ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:58:35.882658Z","caller":"traceutil/trace.go:171","msg":"trace[1282161253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"480.206334ms","start":"2026-06-15T18:58:35.402437Z","end":"2026-06-15T18:58:35.882643Z","steps":["trace[1282161253] 'range keys from in-memory index tree' (duration: 479.984175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:35.883118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:35.40242Z","time spent":"480.678824ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T18:58:39.320392Z","caller":"traceutil/trace.go:171","msg":"trace[490543306] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"100.681616ms","start":"2026-06-15T18:58:39.219685Z","end":"2026-06-15T18:58:39.320367Z","steps":["trace[490543306] 'process raft request' (duration: 100.474107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:57.953223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336103976601477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T18:58:58.188865Z","caller":"traceutil/trace.go:171","msg":"trace[860494198] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"736.479683ms","start":"2026-06-15T18:58:57.452344Z","end":"2026-06-15T18:58:58.188823Z","steps":["trace[860494198] 'read index received' (duration: 735.346796ms)","trace[860494198] 'applied index is now lower than readState.Index' (duration: 1.131187ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T18:58:58.188895Z","caller":"traceutil/trace.go:171","msg":"trace[75893115] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"738.884163ms","start":"2026-06-15T18:58:57.44995Z","end":"2026-06-15T18:58:58.188834Z","steps":["trace[75893115] 'process raft request' (duration: 737.751686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:58.189379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:57.449919Z","time spent":"739.081969ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T18:58:58.191689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.923571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:58:58.191762Z","caller":"traceutil/trace.go:171","msg":"trace[322008427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"547.036255ms","start":"2026-06-15T18:58:57.644706Z","end":"2026-06-15T18:58:58.191743Z","steps":["trace[322008427] 'agreement among raft nodes before linearized reading' (duration: 546.915031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:58.191772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.383918ms","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-15T18:58:58.1919Z","caller":"traceutil/trace.go:171","msg":"trace[1843989568] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:527; }","duration":"739.552553ms","start":"2026-06-15T18:58:57.452315Z","end":"2026-06-15T18:58:58.191868Z","steps":["trace[1843989568] 'agreement among raft nodes before linearized reading' (duration: 736.685669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:58.191966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419025ms","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-15T18:58:58.192009Z","caller":"traceutil/trace.go:171","msg":"trace[504078734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"109.494229ms","start":"2026-06-15T18:58:58.082505Z","end":"2026-06-15T18:58:58.191999Z","steps":["trace[504078734] 'agreement among raft nodes before linearized reading' (duration: 109.349544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:58:58.191961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:57.452305Z","time spent":"739.643206ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-15T18:58:58.191821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:58:57.644683Z","time spent":"547.129699ms","remote":"127.0.0.1:56540","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-15T18:59:00.169262Z","caller":"traceutil/trace.go:171","msg":"trace[1383947991] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"250.759843ms","start":"2026-06-15T18:58:59.918463Z","end":"2026-06-15T18:59:00.169223Z","steps":["trace[1383947991] 'read index received' (duration: 154.341061ms)","trace[1383947991] 'applied index is now lower than readState.Index' (duration: 96.416852ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T18:59:00.169699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.209557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-15T18:59:00.169683Z","caller":"traceutil/trace.go:171","msg":"trace[1640053146] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"253.044287ms","start":"2026-06-15T18:58:59.91659Z","end":"2026-06-15T18:59:00.169634Z","steps":["trace[1640053146] 'process raft request' (duration: 156.235422ms)","trace[1640053146] 'compare' (duration: 96.140474ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T18:59:00.169772Z","caller":"traceutil/trace.go:171","msg":"trace[1953089647] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"251.343601ms","start":"2026-06-15T18:58:59.918411Z","end":"2026-06-15T18:59:00.169755Z","steps":["trace[1953089647] 'agreement among raft nodes before linearized reading' (duration: 251.073343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.169971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.19036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170007Z","caller":"traceutil/trace.go:171","msg":"trace[1750528420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:602; }","duration":"100.031649ms","start":"2026-06-15T18:59:00.069959Z","end":"2026-06-15T18:59:00.169991Z","steps":["trace[1750528420] 'agreement among raft nodes before linearized reading' (duration: 99.989987ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:00.17003Z","caller":"traceutil/trace.go:171","msg":"trace[1587414627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:602; }","duration":"248.300404ms","start":"2026-06-15T18:58:59.921715Z","end":"2026-06-15T18:59:00.170015Z","steps":["trace[1587414627] 'agreement among raft nodes before linearized reading' (duration: 248.18314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.170278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.881429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170322Z","caller":"traceutil/trace.go:171","msg":"trace[1045656634] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"121.939991ms","start":"2026-06-15T18:59:00.048371Z","end":"2026-06-15T18:59:00.170311Z","steps":["trace[1045656634] 'agreement among raft nodes before linearized reading' (duration: 121.876459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.170485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.456488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170524Z","caller":"traceutil/trace.go:171","msg":"trace[1723040287] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"122.51701ms","start":"2026-06-15T18:59:00.047998Z","end":"2026-06-15T18:59:00.170515Z","steps":["trace[1723040287] 'agreement among raft nodes before linearized reading' (duration: 122.457129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.170536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.418022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170561Z","caller":"traceutil/trace.go:171","msg":"trace[1486288718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:602; }","duration":"100.454193ms","start":"2026-06-15T18:59:00.070098Z","end":"2026-06-15T18:59:00.170552Z","steps":["trace[1486288718] 'agreement among raft nodes before linearized reading' (duration: 100.412551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.17066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.703767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170695Z","caller":"traceutil/trace.go:171","msg":"trace[1251152584] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"122.755208ms","start":"2026-06-15T18:59:00.047927Z","end":"2026-06-15T18:59:00.170683Z","steps":["trace[1251152584] 'agreement among raft nodes before linearized reading' (duration: 122.704016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.170835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.863649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170865Z","caller":"traceutil/trace.go:171","msg":"trace[1757729251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"140.926871ms","start":"2026-06-15T18:59:00.02993Z","end":"2026-06-15T18:59:00.170857Z","steps":["trace[1757729251] 'agreement among raft nodes before linearized reading' (duration: 140.861499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.170875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.744492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.170899Z","caller":"traceutil/trace.go:171","msg":"trace[1038495232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:602; }","duration":"100.785493ms","start":"2026-06-15T18:59:00.070107Z","end":"2026-06-15T18:59:00.170893Z","steps":["trace[1038495232] 'agreement among raft nodes before linearized reading' (duration: 100.750322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.485649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171084Z","caller":"traceutil/trace.go:171","msg":"trace[1861418352] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"141.51763ms","start":"2026-06-15T18:59:00.029557Z","end":"2026-06-15T18:59:00.171075Z","steps":["trace[1861418352] 'agreement among raft nodes before linearized reading' (duration: 141.468689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.726286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171279Z","caller":"traceutil/trace.go:171","msg":"trace[96107340] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"141.757217ms","start":"2026-06-15T18:59:00.029513Z","end":"2026-06-15T18:59:00.17127Z","steps":["trace[96107340] 'agreement among raft nodes before linearized reading' (duration: 141.710946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.447677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171338Z","caller":"traceutil/trace.go:171","msg":"trace[1254960601] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:602; }","duration":"202.484977ms","start":"2026-06-15T18:58:59.968846Z","end":"2026-06-15T18:59:00.171331Z","steps":["trace[1254960601] 'agreement among raft nodes before linearized reading' (duration: 202.447866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.957824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171427Z","caller":"traceutil/trace.go:171","msg":"trace[733897980] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:602; }","duration":"202.997865ms","start":"2026-06-15T18:58:59.968423Z","end":"2026-06-15T18:59:00.171421Z","steps":["trace[733897980] 'agreement among raft nodes before linearized reading' (duration: 202.971134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.451129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171529Z","caller":"traceutil/trace.go:171","msg":"trace[755629007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:602; }","duration":"203.509541ms","start":"2026-06-15T18:58:59.968014Z","end":"2026-06-15T18:59:00.171524Z","steps":["trace[755629007] 'agreement among raft nodes before linearized reading' (duration: 203.484041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.171617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.598405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:00.171633Z","caller":"traceutil/trace.go:171","msg":"trace[60744952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:602; }","duration":"249.674958ms","start":"2026-06-15T18:58:59.921953Z","end":"2026-06-15T18:59:00.171628Z","steps":["trace[60744952] 'agreement among raft nodes before linearized reading' (duration: 249.625156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:00.7161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336103976601600,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T18:59:01.18993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.20108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T18:59:01.190053Z","caller":"traceutil/trace.go:171","msg":"trace[2096733628] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"992.294387ms","start":"2026-06-15T18:59:00.197736Z","end":"2026-06-15T18:59:01.19003Z","steps":["trace[2096733628] 'process raft request' (duration: 627.476828ms)","trace[2096733628] 'compare' (duration: 363.655843ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T18:59:01.19011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.197715Z","time spent":"992.36992ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T18:59:01.206058Z","caller":"traceutil/trace.go:171","msg":"trace[346699099] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"992.298372ms","start":"2026-06-15T18:59:00.213747Z","end":"2026-06-15T18:59:01.206046Z","steps":["trace[346699099] 'read index received' (duration: 611.607949ms)","trace[346699099] 'applied index is now lower than readState.Index' (duration: 380.689803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T18:59:01.206165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.413256ms","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-15T18:59:01.206188Z","caller":"traceutil/trace.go:171","msg":"trace[948026852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"992.458997ms","start":"2026-06-15T18:59:00.213722Z","end":"2026-06-15T18:59:01.206181Z","steps":["trace[948026852] 'agreement among raft nodes before linearized reading' (duration: 992.406786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.213707Z","time spent":"992.498699ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T18:59:01.206178Z","caller":"traceutil/trace.go:171","msg":"trace[1907133152] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"525.158277ms","start":"2026-06-15T18:59:00.680977Z","end":"2026-06-15T18:59:01.206135Z","steps":["trace[1907133152] 'process raft request' (duration: 524.809466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.341518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.206383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.858845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.206468Z","caller":"traceutil/trace.go:171","msg":"trace[490743151] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"681.383439ms","start":"2026-06-15T18:59:00.525076Z","end":"2026-06-15T18:59:01.20646Z","steps":["trace[490743151] 'agreement among raft nodes before linearized reading' (duration: 681.321797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.525056Z","time spent":"681.42655ms","remote":"127.0.0.1:45294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-06-15T18:59:01.206443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.488508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.206598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.156834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.206598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.954256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.206624Z","caller":"traceutil/trace.go:171","msg":"trace[1146185759] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:604; }","duration":"620.194615ms","start":"2026-06-15T18:59:00.586424Z","end":"2026-06-15T18:59:01.206619Z","steps":["trace[1146185759] 'agreement among raft nodes before linearized reading' (duration: 620.156074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.20664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.586419Z","time spent":"620.216697ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-06-15T18:59:01.206638Z","caller":"traceutil/trace.go:171","msg":"trace[506670502] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"850.007827ms","start":"2026-06-15T18:59:00.356619Z","end":"2026-06-15T18:59:01.206627Z","steps":["trace[506670502] 'agreement among raft nodes before linearized reading' (duration: 849.936635ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:01.206617Z","caller":"traceutil/trace.go:171","msg":"trace[1938414985] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"992.690644ms","start":"2026-06-15T18:59:00.213871Z","end":"2026-06-15T18:59:01.206562Z","steps":["trace[1938414985] 'agreement among raft nodes before linearized reading' (duration: 992.427366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.356615Z","time spent":"850.060509ms","remote":"127.0.0.1:45294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T18:59:01.206723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.321499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.206743Z","caller":"traceutil/trace.go:171","msg":"trace[1202852752] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:604; }","duration":"620.380262ms","start":"2026-06-15T18:59:00.586358Z","end":"2026-06-15T18:59:01.206738Z","steps":["trace[1202852752] 'agreement among raft nodes before linearized reading' (duration: 620.350321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.586322Z","time spent":"620.431293ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T18:59:01.206726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.21386Z","time spent":"992.85004ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T18:59:01.206856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.145665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.206878Z","caller":"traceutil/trace.go:171","msg":"trace[1789989966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"635.169646ms","start":"2026-06-15T18:59:00.571702Z","end":"2026-06-15T18:59:01.206872Z","steps":["trace[1789989966] 'agreement among raft nodes before linearized reading' (duration: 635.104344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.571683Z","time spent":"635.205287ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T18:59:01.206983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.309331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.207002Z","caller":"traceutil/trace.go:171","msg":"trace[284875549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"635.342232ms","start":"2026-06-15T18:59:00.571655Z","end":"2026-06-15T18:59:01.206997Z","steps":["trace[284875549] 'agreement among raft nodes before linearized reading' (duration: 635.308951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.206988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.009989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.571649Z","time spent":"635.364333ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-06-15T18:59:01.207039Z","caller":"traceutil/trace.go:171","msg":"trace[1066477849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"682.073422ms","start":"2026-06-15T18:59:00.52495Z","end":"2026-06-15T18:59:01.207024Z","steps":["trace[1066477849] 'agreement among raft nodes before linearized reading' (duration: 681.995509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.443886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.531335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.207112Z","caller":"traceutil/trace.go:171","msg":"trace[772433784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"635.484216ms","start":"2026-06-15T18:59:00.571619Z","end":"2026-06-15T18:59:01.207104Z","steps":["trace[772433784] 'agreement among raft nodes before linearized reading' (duration: 635.452355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.524945Z","time spent":"682.144864ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T18:59:01.207132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.571609Z","time spent":"635.517398ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-06-15T18:59:01.20722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.039001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-06-15T18:59:01.207248Z","caller":"traceutil/trace.go:171","msg":"trace[502811142] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"990.076312ms","start":"2026-06-15T18:59:00.217165Z","end":"2026-06-15T18:59:01.207242Z","steps":["trace[502811142] 'agreement among raft nodes before linearized reading' (duration: 989.969888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.463636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.217159Z","time spent":"990.104052ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31150,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-15T18:59:01.207299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.320875ms","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:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.393281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.236479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:01.207388Z","caller":"traceutil/trace.go:171","msg":"trace[2061525324] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"659.407014ms","start":"2026-06-15T18:59:00.547973Z","end":"2026-06-15T18:59:01.20738Z","steps":["trace[2061525324] 'agreement among raft nodes before linearized reading' (duration: 659.238009ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:01.207414Z","caller":"traceutil/trace.go:171","msg":"trace[1414400425] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"993.418749ms","start":"2026-06-15T18:59:00.21392Z","end":"2026-06-15T18:59:01.207338Z","steps":["trace[1414400425] 'agreement among raft nodes before linearized reading' (duration: 993.309885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.097789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T18:59:01.207501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.21391Z","time spent":"993.551713ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-15T18:59:01.207526Z","caller":"traceutil/trace.go:171","msg":"trace[945181380] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"993.609314ms","start":"2026-06-15T18:59:00.213911Z","end":"2026-06-15T18:59:01.20752Z","steps":["trace[945181380] 'agreement among raft nodes before linearized reading' (duration: 993.127309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.213883Z","time spent":"993.658346ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-06-15T18:59:01.207587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.936359ms","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-15T18:59:01.206506Z","caller":"traceutil/trace.go:171","msg":"trace[117171540] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:604; }","duration":"620.032081ms","start":"2026-06-15T18:59:00.586448Z","end":"2026-06-15T18:59:01.20648Z","steps":["trace[117171540] 'agreement among raft nodes before linearized reading' (duration: 619.805894ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:01.207617Z","caller":"traceutil/trace.go:171","msg":"trace[921747607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"692.984241ms","start":"2026-06-15T18:59:00.514625Z","end":"2026-06-15T18:59:01.20761Z","steps":["trace[921747607] 'agreement among raft nodes before linearized reading' (duration: 692.911539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.514612Z","time spent":"693.025642ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T18:59:01.207429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.547968Z","time spent":"659.442715ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T18:59:01.207704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.586443Z","time spent":"621.212499ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-06-15T18:59:01.207122Z","caller":"traceutil/trace.go:171","msg":"trace[2027553879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"850.576747ms","start":"2026-06-15T18:59:00.356535Z","end":"2026-06-15T18:59:01.207111Z","steps":["trace[2027553879] 'agreement among raft nodes before linearized reading' (duration: 850.546286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.356519Z","time spent":"851.306689ms","remote":"127.0.0.1:45294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-06-15T18:59:01.207279Z","caller":"traceutil/trace.go:171","msg":"trace[819505535] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"659.497227ms","start":"2026-06-15T18:59:00.547776Z","end":"2026-06-15T18:59:01.207273Z","steps":["trace[819505535] 'agreement among raft nodes before linearized reading' (duration: 659.460325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.207974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.547769Z","time spent":"660.160178ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T18:59:01.208102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.680932Z","time spent":"525.354774ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T18:59:01.208278Z","caller":"traceutil/trace.go:171","msg":"trace[1047912970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"682.460424ms","start":"2026-06-15T18:59:00.524911Z","end":"2026-06-15T18:59:01.207371Z","steps":["trace[1047912970] 'agreement among raft nodes before linearized reading' (duration: 682.383301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:01.208446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:00.5249Z","time spent":"683.486626ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-06-15T18:59:05.360187Z","caller":"traceutil/trace.go:171","msg":"trace[815437056] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"265.562213ms","start":"2026-06-15T18:59:05.094553Z","end":"2026-06-15T18:59:05.360115Z","steps":["trace[815437056] 'read index received' (duration: 263.880691ms)","trace[815437056] 'applied index is now lower than readState.Index' (duration: 1.680452ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T18:59:05.360218Z","caller":"traceutil/trace.go:171","msg":"trace[420899542] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"378.355642ms","start":"2026-06-15T18:59:04.981822Z","end":"2026-06-15T18:59:05.360178Z","steps":["trace[420899542] 'process raft request' (duration: 377.171016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.360879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.09489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T18:59:05.36095Z","caller":"traceutil/trace.go:171","msg":"trace[999977427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"155.133151ms","start":"2026-06-15T18:59:05.205773Z","end":"2026-06-15T18:59:05.360906Z","steps":["trace[999977427] 'agreement among raft nodes before linearized reading' (duration: 155.004847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.36101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.560874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-15T18:59:05.360547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.958565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zn5qq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-15T18:59:05.361084Z","caller":"traceutil/trace.go:171","msg":"trace[77238475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"133.721189ms","start":"2026-06-15T18:59:05.22733Z","end":"2026-06-15T18:59:05.361051Z","steps":["trace[77238475] 'agreement among raft nodes before linearized reading' (duration: 133.486072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.361035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:04.981793Z","time spent":"379.126515ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T18:59:05.361112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.07616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-15T18:59:05.361142Z","caller":"traceutil/trace.go:171","msg":"trace[1975091297] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zn5qq; range_end:; response_count:1; response_revision:629; }","duration":"266.568753ms","start":"2026-06-15T18:59:05.094509Z","end":"2026-06-15T18:59:05.361078Z","steps":["trace[1975091297] 'agreement among raft nodes before linearized reading' (duration: 265.81791ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:05.361179Z","caller":"traceutil/trace.go:171","msg":"trace[228078634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"135.184284ms","start":"2026-06-15T18:59:05.225972Z","end":"2026-06-15T18:59:05.361156Z","steps":["trace[228078634] 'agreement among raft nodes before linearized reading' (duration: 134.926097ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:05.792804Z","caller":"traceutil/trace.go:171","msg":"trace[1869404319] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"416.32059ms","start":"2026-06-15T18:59:05.376448Z","end":"2026-06-15T18:59:05.792769Z","steps":["trace[1869404319] 'process raft request' (duration: 416.125735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.793003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:05.37644Z","time spent":"416.458265ms","remote":"127.0.0.1:56712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T18:59:05.80886Z","caller":"traceutil/trace.go:171","msg":"trace[755477448] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"362.894375ms","start":"2026-06-15T18:59:05.445931Z","end":"2026-06-15T18:59:05.808826Z","steps":["trace[755477448] 'read index received' (duration: 346.810505ms)","trace[755477448] 'applied index is now lower than readState.Index' (duration: 16.08237ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T18:59:05.808945Z","caller":"traceutil/trace.go:171","msg":"trace[1794468693] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"384.323437ms","start":"2026-06-15T18:59:05.424587Z","end":"2026-06-15T18:59:05.808911Z","steps":["trace[1794468693] 'process raft request' (duration: 383.984057ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:05.809002Z","caller":"traceutil/trace.go:171","msg":"trace[1970910521] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"103.638294ms","start":"2026-06-15T18:59:05.705274Z","end":"2026-06-15T18:59:05.808912Z","steps":["trace[1970910521] 'process raft request' (duration: 103.468299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.809173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:05.424552Z","time spent":"384.540334ms","remote":"127.0.0.1:56884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T18:59:05.809297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.287706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-15T18:59:05.809377Z","caller":"traceutil/trace.go:171","msg":"trace[2040645042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"363.480732ms","start":"2026-06-15T18:59:05.445882Z","end":"2026-06-15T18:59:05.809362Z","steps":["trace[2040645042] 'agreement among raft nodes before linearized reading' (duration: 363.120701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:05.809458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:05.445869Z","time spent":"363.576195ms","remote":"127.0.0.1:56816","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-15T18:59:07.143498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336103976601685,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T18:59:07.305747Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06310027s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T18:59:07.308572Z","caller":"traceutil/trace.go:171","msg":"trace[1985453743] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"665.956627ms","start":"2026-06-15T18:59:06.642542Z","end":"2026-06-15T18:59:07.308499Z","steps":["trace[1985453743] 'read index received' (duration: 663.411191ms)","trace[1985453743] 'applied index is now lower than readState.Index' (duration: 2.543406ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T18:59:07.308923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.324307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:07.308963Z","caller":"traceutil/trace.go:171","msg":"trace[1266887753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"666.444071ms","start":"2026-06-15T18:59:06.642505Z","end":"2026-06-15T18:59:07.308949Z","steps":["trace[1266887753] 'agreement among raft nodes before linearized reading' (duration: 666.169723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:07.309011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:06.642423Z","time spent":"666.577855ms","remote":"127.0.0.1:56540","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-15T18:59:07.312232Z","caller":"traceutil/trace.go:171","msg":"trace[68549043] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.070905092s","start":"2026-06-15T18:59:06.241306Z","end":"2026-06-15T18:59:07.312211Z","steps":["trace[68549043] 'process raft request' (duration: 1.064868042s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:07.31237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:06.241275Z","time spent":"1.071019105s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T18:59:08.51473Z","caller":"traceutil/trace.go:171","msg":"trace[1223796367] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"218.277581ms","start":"2026-06-15T18:59:08.296404Z","end":"2026-06-15T18:59:08.514682Z","steps":["trace[1223796367] 'process raft request' (duration: 215.859299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:24.016491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.957014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T18:59:24.016637Z","caller":"traceutil/trace.go:171","msg":"trace[433699761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"516.173059ms","start":"2026-06-15T18:59:23.500429Z","end":"2026-06-15T18:59:24.016602Z","steps":["trace[433699761] 'range keys from in-memory index tree' (duration: 515.710308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:24.016693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:23.500402Z","time spent":"516.279522ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T18:59:24.016724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.289096ms","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-15T18:59:24.016832Z","caller":"traceutil/trace.go:171","msg":"trace[1499856693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"533.4455ms","start":"2026-06-15T18:59:23.48336Z","end":"2026-06-15T18:59:24.016806Z","steps":["trace[1499856693] 'range keys from in-memory index tree' (duration: 533.051421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:24.016874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:23.483337Z","time spent":"533.527853ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T18:59:24.016718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.421779ms","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-15T18:59:24.017087Z","caller":"traceutil/trace.go:171","msg":"trace[1495460295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"542.833229ms","start":"2026-06-15T18:59:23.474225Z","end":"2026-06-15T18:59:24.017058Z","steps":["trace[1495460295] 'range keys from in-memory index tree' (duration: 542.230415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:24.017123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T18:59:23.4742Z","time spent":"542.912602ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T18:59:24.207679Z","caller":"traceutil/trace.go:171","msg":"trace[1208871141] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"185.957924ms","start":"2026-06-15T18:59:24.021681Z","end":"2026-06-15T18:59:24.207639Z","steps":["trace[1208871141] 'process raft request' (duration: 185.672987ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:24.207679Z","caller":"traceutil/trace.go:171","msg":"trace[162021813] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"185.961184ms","start":"2026-06-15T18:59:24.021682Z","end":"2026-06-15T18:59:24.207643Z","steps":["trace[162021813] 'process raft request' (duration: 185.859951ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T18:59:24.207796Z","caller":"traceutil/trace.go:171","msg":"trace[463377799] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"185.623135ms","start":"2026-06-15T18:59:24.022159Z","end":"2026-06-15T18:59:24.207782Z","steps":["trace[463377799] 'process raft request' (duration: 185.438201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T18:59:59.546106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.728586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T18:59:59.54626Z","caller":"traceutil/trace.go:171","msg":"trace[1785830549] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1235; }","duration":"102.98512ms","start":"2026-06-15T18:59:59.443246Z","end":"2026-06-15T18:59:59.546232Z","steps":["trace[1785830549] 'count revisions from in-memory index tree' (duration: 102.42125ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:16.918224Z","caller":"traceutil/trace.go:171","msg":"trace[983854773] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"124.371865ms","start":"2026-06-15T19:00:16.79382Z","end":"2026-06-15T19:00:16.918192Z","steps":["trace[983854773] 'process raft request' (duration: 123.993899ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:37.240333Z","caller":"traceutil/trace.go:171","msg":"trace[2125729660] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"132.285635ms","start":"2026-06-15T19:00:37.108021Z","end":"2026-06-15T19:00:37.240307Z","steps":["trace[2125729660] 'process raft request' (duration: 132.107603ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:54.418725Z","caller":"traceutil/trace.go:171","msg":"trace[1504718386] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"328.694717ms","start":"2026-06-15T19:00:54.089992Z","end":"2026-06-15T19:00:54.418687Z","steps":["trace[1504718386] 'process raft request' (duration: 328.417153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.419107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.089948Z","time spent":"328.89873ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:54.943543Z","caller":"traceutil/trace.go:171","msg":"trace[792555440] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"290.209467ms","start":"2026-06-15T19:00:54.653302Z","end":"2026-06-15T19:00:54.943511Z","steps":["trace[792555440] 'read index received' (duration: 290.043285ms)","trace[792555440] 'applied index is now lower than readState.Index' (duration: 163.952µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:54.943846Z","caller":"traceutil/trace.go:171","msg":"trace[419411082] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"293.532423ms","start":"2026-06-15T19:00:54.650046Z","end":"2026-06-15T19:00:54.943578Z","steps":["trace[419411082] 'process raft request' (duration: 293.274689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.944057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.661853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b80a7d53-825a-4887-9464-fb2b3b95ba5d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-15T19:00:54.944179Z","caller":"traceutil/trace.go:171","msg":"trace[911708438] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b80a7d53-825a-4887-9464-fb2b3b95ba5d; range_end:; response_count:1; response_revision:1926; }","duration":"290.856195ms","start":"2026-06-15T19:00:54.653293Z","end":"2026-06-15T19:00:54.944149Z","steps":["trace[911708438] 'agreement among raft nodes before linearized reading' (duration: 290.349069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.970072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.564757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-06-15T19:00:54.970143Z","caller":"traceutil/trace.go:171","msg":"trace[1583986754] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1928; }","duration":"128.657648ms","start":"2026-06-15T19:00:54.84147Z","end":"2026-06-15T19:00:54.970128Z","steps":["trace[1583986754] 'agreement among raft nodes before linearized reading' (duration: 128.475466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.970355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.57428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-15T19:00:54.970448Z","caller":"traceutil/trace.go:171","msg":"trace[1590907328] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1928; }","duration":"237.721854ms","start":"2026-06-15T19:00:54.732705Z","end":"2026-06-15T19:00:54.970427Z","steps":["trace[1590907328] 'agreement among raft nodes before linearized reading' (duration: 237.452809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.970652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.747627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-15T19:00:54.970613Z","caller":"traceutil/trace.go:171","msg":"trace[2123852863] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"206.871882ms","start":"2026-06-15T19:00:54.763714Z","end":"2026-06-15T19:00:54.970586Z","steps":["trace[2123852863] 'process raft request' (duration: 194.410947ms)","trace[2123852863] 'compare' (duration: 11.536291ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:54.970693Z","caller":"traceutil/trace.go:171","msg":"trace[1693593764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1928; }","duration":"248.806288ms","start":"2026-06-15T19:00:54.721875Z","end":"2026-06-15T19:00:54.970681Z","steps":["trace[1693593764] 'agreement among raft nodes before linearized reading' (duration: 248.711457ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:54.970806Z","caller":"traceutil/trace.go:171","msg":"trace[664793929] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"154.025273ms","start":"2026-06-15T19:00:54.816769Z","end":"2026-06-15T19:00:54.970794Z","steps":["trace[664793929] 'process raft request' (duration: 153.021428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.970772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.518032ms","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-15T19:00:54.970876Z","caller":"traceutil/trace.go:171","msg":"trace[72078142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1928; }","duration":"250.658514ms","start":"2026-06-15T19:00:54.720194Z","end":"2026-06-15T19:00:54.970853Z","steps":["trace[72078142] 'agreement among raft nodes before linearized reading' (duration: 250.446781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.970071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.199276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T19:00:54.971046Z","caller":"traceutil/trace.go:171","msg":"trace[1650301836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1928; }","duration":"276.194201ms","start":"2026-06-15T19:00:54.694808Z","end":"2026-06-15T19:00:54.971002Z","steps":["trace[1650301836] 'agreement among raft nodes before linearized reading' (duration: 275.075465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:54.971292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.9838ms","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-15T19:00:54.971383Z","caller":"traceutil/trace.go:171","msg":"trace[322211649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"176.681939ms","start":"2026-06-15T19:00:54.794684Z","end":"2026-06-15T19:00:54.971366Z","steps":["trace[322211649] 'agreement among raft nodes before linearized reading' (duration: 175.667875ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:55.803692Z","caller":"traceutil/trace.go:171","msg":"trace[560174038] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"823.782977ms","start":"2026-06-15T19:00:54.979885Z","end":"2026-06-15T19:00:55.803668Z","steps":["trace[560174038] 'process raft request' (duration: 823.595145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.803845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.979862Z","time spent":"823.901148ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.804178Z","caller":"traceutil/trace.go:171","msg":"trace[2052607663] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"824.188453ms","start":"2026-06-15T19:00:54.979975Z","end":"2026-06-15T19:00:55.804163Z","steps":["trace[2052607663] 'process raft request' (duration: 823.644565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.804279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.979957Z","time spent":"824.258314ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.804247Z","caller":"traceutil/trace.go:171","msg":"trace[1896700678] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2006; }","duration":"818.057537ms","start":"2026-06-15T19:00:54.986156Z","end":"2026-06-15T19:00:55.804213Z","steps":["trace[1896700678] 'read index received' (duration: 45.509244ms)","trace[1896700678] 'applied index is now lower than readState.Index' (duration: 772.547133ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:55.804394Z","caller":"traceutil/trace.go:171","msg":"trace[1018713688] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"787.323478ms","start":"2026-06-15T19:00:55.017055Z","end":"2026-06-15T19:00:55.804379Z","steps":["trace[1018713688] 'process raft request' (duration: 787.100725ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:55.804341Z","caller":"traceutil/trace.go:171","msg":"trace[1193604645] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"824.275444ms","start":"2026-06-15T19:00:54.980019Z","end":"2026-06-15T19:00:55.804295Z","steps":["trace[1193604645] 'process raft request' (duration: 823.9839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.804541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.413417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T19:00:55.804574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:55.01704Z","time spent":"787.40944ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:00:55.80463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.471902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:00:55.804643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.980006Z","time spent":"824.517167ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.804588Z","caller":"traceutil/trace.go:171","msg":"trace[207647813] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1933; }","duration":"595.460877ms","start":"2026-06-15T19:00:55.209116Z","end":"2026-06-15T19:00:55.804577Z","steps":["trace[207647813] 'agreement among raft nodes before linearized reading' (duration: 595.345776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.804776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:55.209049Z","time spent":"595.714001ms","remote":"127.0.0.1:56884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:00:55.804782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.447142ms","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-15T19:00:55.804665Z","caller":"traceutil/trace.go:171","msg":"trace[579708435] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1933; }","duration":"818.525554ms","start":"2026-06-15T19:00:54.986131Z","end":"2026-06-15T19:00:55.804657Z","steps":["trace[579708435] 'agreement among raft nodes before linearized reading' (duration: 818.475653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.804889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.986123Z","time spent":"818.744536ms","remote":"127.0.0.1:57008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-15T19:00:55.804818Z","caller":"traceutil/trace.go:171","msg":"trace[750754470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1933; }","duration":"249.537773ms","start":"2026-06-15T19:00:55.555273Z","end":"2026-06-15T19:00:55.804811Z","steps":["trace[750754470] 'agreement among raft nodes before linearized reading' (duration: 249.412101ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:29.779709Z","caller":"traceutil/trace.go:171","msg":"trace[1566528097] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"131.524498ms","start":"2026-06-15T19:01:29.648156Z","end":"2026-06-15T19:01:29.77968Z","steps":["trace[1566528097] 'read index received' (duration: 131.227994ms)","trace[1566528097] 'applied index is now lower than readState.Index' (duration: 294.834µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:29.779796Z","caller":"traceutil/trace.go:171","msg":"trace[1905689781] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"184.145131ms","start":"2026-06-15T19:01:29.595594Z","end":"2026-06-15T19:01:29.779739Z","steps":["trace[1905689781] 'process raft request' (duration: 183.891328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:29.779951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.77032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:29.780261Z","caller":"traceutil/trace.go:171","msg":"trace[1108176755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"131.961963ms","start":"2026-06-15T19:01:29.648108Z","end":"2026-06-15T19:01:29.78007Z","steps":["trace[1108176755] 'agreement among raft nodes before linearized reading' (duration: 131.77169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:32.903873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.076136ms","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-15T19:01:32.903963Z","caller":"traceutil/trace.go:171","msg":"trace[1216674425] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2174; }","duration":"657.222697ms","start":"2026-06-15T19:01:32.246718Z","end":"2026-06-15T19:01:32.903941Z","steps":["trace[1216674425] 'range keys from in-memory index tree' (duration: 656.837292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:32.903981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.102334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:01:32.904092Z","caller":"traceutil/trace.go:171","msg":"trace[1756745269] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2174; }","duration":"342.267076ms","start":"2026-06-15T19:01:32.561807Z","end":"2026-06-15T19:01:32.904074Z","steps":["trace[1756745269] 'count revisions from in-memory index tree' (duration: 342.011443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:32.904133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:32.561786Z","time spent":"342.337807ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:01:32.904101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.343331ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:32.904177Z","caller":"traceutil/trace.go:171","msg":"trace[1534536246] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2174; }","duration":"573.421362ms","start":"2026-06-15T19:01:32.330745Z","end":"2026-06-15T19:01:32.904166Z","steps":["trace[1534536246] 'range keys from in-memory index tree' (duration: 573.335121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:32.904008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:32.24669Z","time spent":"657.307867ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-15T19:01:32.903989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.228962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:32.904315Z","caller":"traceutil/trace.go:171","msg":"trace[2033854412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2174; }","duration":"259.610186ms","start":"2026-06-15T19:01:32.644674Z","end":"2026-06-15T19:01:32.904284Z","steps":["trace[2033854412] 'range keys from in-memory index tree' (duration: 259.13464ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:32.904726Z","caller":"traceutil/trace.go:171","msg":"trace[589137070] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"223.926525ms","start":"2026-06-15T19:01:32.680728Z","end":"2026-06-15T19:01:32.904654Z","steps":["trace[589137070] 'process raft request' (duration: 148.278591ms)","trace[589137070] 'compare' (duration: 74.777294ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:56.253625Z","caller":"traceutil/trace.go:171","msg":"trace[1171715483] linearizableReadLoop","detail":"{readStateIndex:2397; appliedIndex:2395; }","duration":"107.161637ms","start":"2026-06-15T19:01:56.146426Z","end":"2026-06-15T19:01:56.253587Z","steps":["trace[1171715483] 'read index received' (duration: 57.869118ms)","trace[1171715483] 'applied index is now lower than readState.Index' (duration: 49.290689ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:56.253743Z","caller":"traceutil/trace.go:171","msg":"trace[1645752625] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"108.351231ms","start":"2026-06-15T19:01:56.145343Z","end":"2026-06-15T19:01:56.253694Z","steps":["trace[1645752625] 'process raft request' (duration: 108.152738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:56.254174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.964759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:56.25419Z","caller":"traceutil/trace.go:171","msg":"trace[94610631] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"109.196741ms","start":"2026-06-15T19:01:56.144953Z","end":"2026-06-15T19:01:56.25415Z","steps":["trace[94610631] 'process raft request' (duration: 59.385047ms)","trace[94610631] 'compare' (duration: 48.941264ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:56.254194Z","caller":"traceutil/trace.go:171","msg":"trace[1366903097] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"106.946554ms","start":"2026-06-15T19:01:56.147196Z","end":"2026-06-15T19:01:56.254143Z","steps":["trace[1366903097] 'process raft request' (duration: 106.348827ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:56.254358Z","caller":"traceutil/trace.go:171","msg":"trace[1205032393] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2306; }","duration":"104.109531ms","start":"2026-06-15T19:01:56.150118Z","end":"2026-06-15T19:01:56.254228Z","steps":["trace[1205032393] 'agreement among raft nodes before linearized reading' (duration: 103.972669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:56.254657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.199458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:01:56.256239Z","caller":"traceutil/trace.go:171","msg":"trace[1652529451] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2306; }","duration":"109.823788ms","start":"2026-06-15T19:01:56.146391Z","end":"2026-06-15T19:01:56.256215Z","steps":["trace[1652529451] 'agreement among raft nodes before linearized reading' (duration: 107.557821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:56.534486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.128034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:01:56.534629Z","caller":"traceutil/trace.go:171","msg":"trace[339541254] linearizableReadLoop","detail":"{readStateIndex:2399; appliedIndex:2398; }","duration":"273.256554ms","start":"2026-06-15T19:01:56.261344Z","end":"2026-06-15T19:01:56.534601Z","steps":["trace[339541254] 'read index received' (duration: 155.858847ms)","trace[339541254] 'applied index is now lower than readState.Index' (duration: 117.395297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:56.535113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.89967ms","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-15T19:01:56.53517Z","caller":"traceutil/trace.go:171","msg":"trace[459228760] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2307; }","duration":"266.96744ms","start":"2026-06-15T19:01:56.268183Z","end":"2026-06-15T19:01:56.53515Z","steps":["trace[459228760] 'agreement among raft nodes before linearized reading' (duration: 266.783688ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:56.535086Z","caller":"traceutil/trace.go:171","msg":"trace[1268526254] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"274.281687ms","start":"2026-06-15T19:01:56.260756Z","end":"2026-06-15T19:01:56.535038Z","steps":["trace[1268526254] 'process raft request' (duration: 156.463015ms)","trace[1268526254] 'compare' (duration: 116.795421ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:56.534924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.539327ms","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:5"} {"level":"info","ts":"2026-06-15T19:01:56.53613Z","caller":"traceutil/trace.go:171","msg":"trace[1548816786] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2307; }","duration":"274.793263ms","start":"2026-06-15T19:01:56.261274Z","end":"2026-06-15T19:01:56.536067Z","steps":["trace[1548816786] 'agreement among raft nodes before linearized reading' (duration: 273.513127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:57.328479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.660369ms","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-15T19:01:57.328584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.611871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:57.328601Z","caller":"traceutil/trace.go:171","msg":"trace[1092765423] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2308; }","duration":"334.875981ms","start":"2026-06-15T19:01:56.993698Z","end":"2026-06-15T19:01:57.328574Z","steps":["trace[1092765423] 'count revisions from in-memory index tree' (duration: 334.541577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:57.328661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:56.993665Z","time spent":"334.981663ms","remote":"127.0.0.1:50576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-15T19:01:57.328694Z","caller":"traceutil/trace.go:171","msg":"trace[2096874177] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2308; }","duration":"388.741213ms","start":"2026-06-15T19:01:56.939889Z","end":"2026-06-15T19:01:57.32863Z","steps":["trace[2096874177] 'count revisions from in-memory index tree' (duration: 388.48205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:57.328748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:56.939862Z","time spent":"388.859284ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:01:57.329684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.788124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:01:57.329869Z","caller":"traceutil/trace.go:171","msg":"trace[750001090] linearizableReadLoop","detail":"{readStateIndex:2401; appliedIndex:2400; }","duration":"182.82359ms","start":"2026-06-15T19:01:57.147005Z","end":"2026-06-15T19:01:57.329829Z","steps":["trace[750001090] 'read index received' (duration: 55.873424ms)","trace[750001090] 'applied index is now lower than readState.Index' (duration: 126.947616ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:57.330089Z","caller":"traceutil/trace.go:171","msg":"trace[1708730798] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"262.225171ms","start":"2026-06-15T19:01:57.067803Z","end":"2026-06-15T19:01:57.330029Z","steps":["trace[1708730798] 'process raft request' (duration: 134.944881ms)","trace[1708730798] 'compare' (duration: 126.132277ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:57.330161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.188284ms","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-15T19:01:57.330415Z","caller":"traceutil/trace.go:171","msg":"trace[1679180154] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2309; }","duration":"183.466508ms","start":"2026-06-15T19:01:57.146899Z","end":"2026-06-15T19:01:57.330366Z","steps":["trace[1679180154] 'agreement among raft nodes before linearized reading' (duration: 183.014882ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:57.513852Z","caller":"traceutil/trace.go:171","msg":"trace[105075252] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"168.931879ms","start":"2026-06-15T19:01:57.344885Z","end":"2026-06-15T19:01:57.513816Z","steps":["trace[105075252] 'process raft request' (duration: 159.411967ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:57.539991Z","caller":"traceutil/trace.go:171","msg":"trace[1873608703] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"194.115173ms","start":"2026-06-15T19:01:57.345837Z","end":"2026-06-15T19:01:57.539952Z","steps":["trace[1873608703] 'process raft request' (duration: 193.87918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:58.269789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.792726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:58.269893Z","caller":"traceutil/trace.go:171","msg":"trace[570731866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2312; }","duration":"622.959877ms","start":"2026-06-15T19:01:57.646911Z","end":"2026-06-15T19:01:58.269871Z","steps":["trace[570731866] 'range keys from in-memory index tree' (duration: 622.621454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:58.26993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:57.646882Z","time spent":"623.040329ms","remote":"127.0.0.1:56540","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-15T19:01:58.283001Z","caller":"traceutil/trace.go:171","msg":"trace[239080634] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"137.459797ms","start":"2026-06-15T19:01:58.145503Z","end":"2026-06-15T19:01:58.282963Z","steps":["trace[239080634] 'process raft request' (duration: 137.236654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:59.029065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.755723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:01:59.030339Z","caller":"traceutil/trace.go:171","msg":"trace[1608972594] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"720.486089ms","start":"2026-06-15T19:01:58.309795Z","end":"2026-06-15T19:01:59.030282Z","steps":["trace[1608972594] 'process raft request' (duration: 508.340601ms)","trace[1608972594] 'compare' (duration: 210.299126ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:59.03057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:58.309788Z","time spent":"720.656131ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:01:59.387177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.859383ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-15T19:01:59.387338Z","caller":"traceutil/trace.go:171","msg":"trace[141963292] linearizableReadLoop","detail":"{readStateIndex:2409; appliedIndex:2407; }","duration":"1.048828229s","start":"2026-06-15T19:01:58.338487Z","end":"2026-06-15T19:01:59.387316Z","steps":["trace[141963292] 'read index received' (duration: 479.643955ms)","trace[141963292] 'applied index is now lower than readState.Index' (duration: 569.182504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:59.387965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049448257s","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-15T19:01:59.389189Z","caller":"traceutil/trace.go:171","msg":"trace[383513841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2316; }","duration":"1.049636678s","start":"2026-06-15T19:01:58.338437Z","end":"2026-06-15T19:01:59.388074Z","steps":["trace[383513841] 'agreement among raft nodes before linearized reading' (duration: 1.049167613s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:59.389472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:58.338423Z","time spent":"1.050891443s","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-15T19:01:59.390551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.169757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T19:01:59.39074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.458165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:59.390824Z","caller":"traceutil/trace.go:171","msg":"trace[700945515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2318; }","duration":"246.559646ms","start":"2026-06-15T19:01:59.144241Z","end":"2026-06-15T19:01:59.3908Z","steps":["trace[700945515] 'agreement among raft nodes before linearized reading' (duration: 246.415084ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:59.390732Z","caller":"traceutil/trace.go:171","msg":"trace[1648432808] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2318; }","duration":"956.403709ms","start":"2026-06-15T19:01:58.434291Z","end":"2026-06-15T19:01:59.390695Z","steps":["trace[1648432808] 'agreement among raft nodes before linearized reading' (duration: 956.091405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:59.391097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:58.434271Z","time spent":"956.803834ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":56,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:01:59.391275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:59.391392Z","caller":"traceutil/trace.go:171","msg":"trace[1049740163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2318; }","duration":"744.428431ms","start":"2026-06-15T19:01:58.646949Z","end":"2026-06-15T19:01:59.391378Z","steps":["trace[1049740163] 'agreement among raft nodes before linearized reading' (duration: 744.28466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:59.391542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:58.646847Z","time spent":"744.681604ms","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T19:01:59.391943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.273617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:01:59.392099Z","caller":"traceutil/trace.go:171","msg":"trace[431955470] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2318; }","duration":"850.353698ms","start":"2026-06-15T19:01:58.541648Z","end":"2026-06-15T19:01:59.392002Z","steps":["trace[431955470] 'agreement among raft nodes before linearized reading' (duration: 850.102336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:59.392166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:58.541599Z","time spent":"850.551441ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-15T19:03:13.822851Z","caller":"traceutil/trace.go:171","msg":"trace[132729993] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"132.071671ms","start":"2026-06-15T19:03:13.69075Z","end":"2026-06-15T19:03:13.822822Z","steps":["trace[132729993] 'process raft request' (duration: 131.899546ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:13.82377Z","caller":"traceutil/trace.go:171","msg":"trace[1831216952] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"130.537528ms","start":"2026-06-15T19:03:13.693205Z","end":"2026-06-15T19:03:13.823742Z","steps":["trace[1831216952] 'process raft request' (duration: 130.283041ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.924285Z","caller":"traceutil/trace.go:171","msg":"trace[813372834] linearizableReadLoop","detail":"{readStateIndex:2977; appliedIndex:2976; }","duration":"201.774814ms","start":"2026-06-15T19:03:22.722484Z","end":"2026-06-15T19:03:22.924258Z","steps":["trace[813372834] 'read index received' (duration: 201.522017ms)","trace[813372834] 'applied index is now lower than readState.Index' (duration: 251.477µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:22.924766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.039623ms","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":"warn","ts":"2026-06-15T19:03:22.924795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.069728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-15T19:03:22.924813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.790506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-15T19:03:22.924828Z","caller":"traceutil/trace.go:171","msg":"trace[63260374] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"243.26761ms","start":"2026-06-15T19:03:22.681516Z","end":"2026-06-15T19:03:22.924784Z","steps":["trace[63260374] 'process raft request' (duration: 242.596452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.924833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.323729ms","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-15T19:03:22.92487Z","caller":"traceutil/trace.go:171","msg":"trace[185483463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2866; }","duration":"170.170161ms","start":"2026-06-15T19:03:22.754682Z","end":"2026-06-15T19:03:22.924852Z","steps":["trace[185483463] 'agreement among raft nodes before linearized reading' (duration: 169.966446ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.92488Z","caller":"traceutil/trace.go:171","msg":"trace[1224629668] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2866; }","duration":"171.891848ms","start":"2026-06-15T19:03:22.752971Z","end":"2026-06-15T19:03:22.924863Z","steps":["trace[1224629668] 'agreement among raft nodes before linearized reading' (duration: 171.698283ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.924929Z","caller":"traceutil/trace.go:171","msg":"trace[1262829363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2866; }","duration":"202.464453ms","start":"2026-06-15T19:03:22.722441Z","end":"2026-06-15T19:03:22.924906Z","steps":["trace[1262829363] 'agreement among raft nodes before linearized reading' (duration: 202.132594ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.924858Z","caller":"traceutil/trace.go:171","msg":"trace[1027933606] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2866; }","duration":"172.258189ms","start":"2026-06-15T19:03:22.752579Z","end":"2026-06-15T19:03:22.924838Z","steps":["trace[1027933606] 'agreement among raft nodes before linearized reading' (duration: 172.044323ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:49.719794Z","caller":"traceutil/trace.go:171","msg":"trace[126894345] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"173.109821ms","start":"2026-06-15T19:03:49.546649Z","end":"2026-06-15T19:03:49.719759Z","steps":["trace[126894345] 'process raft request' (duration: 172.847715ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:55.302697Z","caller":"traceutil/trace.go:171","msg":"trace[1894242824] linearizableReadLoop","detail":"{readStateIndex:3162; appliedIndex:3161; }","duration":"209.473037ms","start":"2026-06-15T19:03:55.093188Z","end":"2026-06-15T19:03:55.302661Z","steps":["trace[1894242824] 'read index received' (duration: 207.633922ms)","trace[1894242824] 'applied index is now lower than readState.Index' (duration: 1.836685ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:03:55.302853Z","caller":"traceutil/trace.go:171","msg":"trace[1612691584] transaction","detail":"{read_only:false; response_revision:3044; number_of_response:1; }","duration":"217.02086ms","start":"2026-06-15T19:03:55.085779Z","end":"2026-06-15T19:03:55.3028Z","steps":["trace[1612691584] 'process raft request' (duration: 215.032392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:55.302993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.34377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:03:55.303096Z","caller":"traceutil/trace.go:171","msg":"trace[1504718176] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3044; }","duration":"116.512154ms","start":"2026-06-15T19:03:55.186561Z","end":"2026-06-15T19:03:55.303073Z","steps":["trace[1504718176] 'agreement among raft nodes before linearized reading' (duration: 116.325099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:55.30301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.794624ms","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-15T19:03:55.303415Z","caller":"traceutil/trace.go:171","msg":"trace[2085130748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3044; }","duration":"210.282556ms","start":"2026-06-15T19:03:55.093122Z","end":"2026-06-15T19:03:55.303405Z","steps":["trace[2085130748] 'agreement among raft nodes before linearized reading' (duration: 209.695822ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:55.681983Z","caller":"traceutil/trace.go:171","msg":"trace[1449715706] linearizableReadLoop","detail":"{readStateIndex:3163; appliedIndex:3162; }","duration":"160.018297ms","start":"2026-06-15T19:03:55.521939Z","end":"2026-06-15T19:03:55.681958Z","steps":["trace[1449715706] 'read index received' (duration: 145.468855ms)","trace[1449715706] 'applied index is now lower than readState.Index' (duration: 14.547812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:55.682154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.201692ms","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:5"} {"level":"info","ts":"2026-06-15T19:03:55.682192Z","caller":"traceutil/trace.go:171","msg":"trace[1664991625] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3045; }","duration":"160.303115ms","start":"2026-06-15T19:03:55.521878Z","end":"2026-06-15T19:03:55.682181Z","steps":["trace[1664991625] 'agreement among raft nodes before linearized reading' (duration: 160.207323ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:55.682034Z","caller":"traceutil/trace.go:171","msg":"trace[592689148] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"373.256555ms","start":"2026-06-15T19:03:55.30874Z","end":"2026-06-15T19:03:55.681997Z","steps":["trace[592689148] 'process raft request' (duration: 358.784285ms)","trace[592689148] 'compare' (duration: 13.927937ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:55.683086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:55.308715Z","time spent":"374.25012ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:03:56.610205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.97778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:03:56.610324Z","caller":"traceutil/trace.go:171","msg":"trace[146003637] transaction","detail":"{read_only:false; response_revision:3047; number_of_response:1; }","duration":"611.71605ms","start":"2026-06-15T19:03:55.998584Z","end":"2026-06-15T19:03:56.610301Z","steps":["trace[146003637] 'process raft request' (duration: 191.540115ms)","trace[146003637] 'compare' (duration: 419.862368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:56.610411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:55.998563Z","time spent":"611.816782ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:03:56.669867Z","caller":"traceutil/trace.go:171","msg":"trace[157100771] linearizableReadLoop","detail":"{readStateIndex:3166; appliedIndex:3164; }","duration":"574.328707ms","start":"2026-06-15T19:03:56.095523Z","end":"2026-06-15T19:03:56.669851Z","steps":["trace[157100771] 'read index received' (duration: 94.613843ms)","trace[157100771] 'applied index is now lower than readState.Index' (duration: 479.713574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:56.670138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.785345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:03:56.670117Z","caller":"traceutil/trace.go:171","msg":"trace[450203832] transaction","detail":"{read_only:false; response_revision:3048; number_of_response:1; }","duration":"671.403372ms","start":"2026-06-15T19:03:55.998645Z","end":"2026-06-15T19:03:56.670048Z","steps":["trace[450203832] 'process raft request' (duration: 671.112235ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:56.67017Z","caller":"traceutil/trace.go:171","msg":"trace[755736989] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3048; }","duration":"571.838606ms","start":"2026-06-15T19:03:56.098321Z","end":"2026-06-15T19:03:56.67016Z","steps":["trace[755736989] 'agreement among raft nodes before linearized reading' (duration: 571.750704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:56.670194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:56.098314Z","time spent":"571.874118ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-15T19:03:56.670114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.562552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:03:56.670281Z","caller":"traceutil/trace.go:171","msg":"trace[383364728] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:3048; }","duration":"574.797408ms","start":"2026-06-15T19:03:56.095468Z","end":"2026-06-15T19:03:56.670266Z","steps":["trace[383364728] 'agreement among raft nodes before linearized reading' (duration: 574.534561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:56.670336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:56.095443Z","time spent":"574.88495ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:03:56.670389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.936806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T19:03:56.670408Z","caller":"traceutil/trace.go:171","msg":"trace[1249525929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3048; }","duration":"539.955347ms","start":"2026-06-15T19:03:56.130448Z","end":"2026-06-15T19:03:56.670403Z","steps":["trace[1249525929] 'agreement among raft nodes before linearized reading' (duration: 539.907076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:56.670425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:56.130422Z","time spent":"539.999688ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T19:03:56.670435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.459671ms","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-15T19:03:56.670477Z","caller":"traceutil/trace.go:171","msg":"trace[1383848244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3048; }","duration":"533.511191ms","start":"2026-06-15T19:03:56.136955Z","end":"2026-06-15T19:03:56.670466Z","steps":["trace[1383848244] 'agreement among raft nodes before linearized reading' (duration: 533.355298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:56.670511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:56.136949Z","time spent":"533.555393ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T19:03:56.670648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.808744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b9567b92620149\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-15T19:03:56.670673Z","caller":"traceutil/trace.go:171","msg":"trace[147238588] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b9567b92620149; range_end:; response_count:1; response_revision:3048; }","duration":"329.883515ms","start":"2026-06-15T19:03:56.340784Z","end":"2026-06-15T19:03:56.670667Z","steps":["trace[147238588] 'agreement among raft nodes before linearized reading' (duration: 329.826064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:56.670689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:56.340757Z","time spent":"329.927136ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b9567b92620149\" "} {"level":"warn","ts":"2026-06-15T19:03:56.670272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:55.998618Z","time spent":"671.570916ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:04:01.848098Z","caller":"traceutil/trace.go:171","msg":"trace[861826413] transaction","detail":"{read_only:false; response_revision:3077; number_of_response:1; }","duration":"136.992287ms","start":"2026-06-15T19:04:01.711079Z","end":"2026-06-15T19:04:01.848071Z","steps":["trace[861826413] 'process raft request' (duration: 136.798662ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:21.361815Z","caller":"traceutil/trace.go:171","msg":"trace[34131743] transaction","detail":"{read_only:false; response_revision:3898; number_of_response:1; }","duration":"113.276838ms","start":"2026-06-15T19:06:21.248509Z","end":"2026-06-15T19:06:21.361786Z","steps":["trace[34131743] 'process raft request' (duration: 113.050554ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:41.836016Z","caller":"traceutil/trace.go:171","msg":"trace[941404772] linearizableReadLoop","detail":"{readStateIndex:4603; appliedIndex:4602; }","duration":"152.820128ms","start":"2026-06-15T19:07:41.683164Z","end":"2026-06-15T19:07:41.835984Z","steps":["trace[941404772] 'read index received' (duration: 152.553654ms)","trace[941404772] 'applied index is now lower than readState.Index' (duration: 265.164µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:07:41.836168Z","caller":"traceutil/trace.go:171","msg":"trace[1568993745] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"167.118748ms","start":"2026-06-15T19:07:41.668959Z","end":"2026-06-15T19:07:41.836077Z","steps":["trace[1568993745] 'process raft request' (duration: 166.850704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:41.836294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.107172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-q9qft\" ","response":"range_response_count:1 size:4357"} {"level":"info","ts":"2026-06-15T19:07:41.836344Z","caller":"traceutil/trace.go:171","msg":"trace[1572690004] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-q9qft; range_end:; response_count:1; response_revision:4421; }","duration":"153.213873ms","start":"2026-06-15T19:07:41.683113Z","end":"2026-06-15T19:07:41.836327Z","steps":["trace[1572690004] 'agreement among raft nodes before linearized reading' (duration: 153.02946ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:49.958217Z","caller":"traceutil/trace.go:171","msg":"trace[1034883472] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"118.167525ms","start":"2026-06-15T19:07:49.840019Z","end":"2026-06-15T19:07:49.958186Z","steps":["trace[1034883472] 'process raft request' (duration: 117.975361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:50.166048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.682267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:07:50.166309Z","caller":"traceutil/trace.go:171","msg":"trace[1182772590] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4994; }","duration":"198.048667ms","start":"2026-06-15T19:07:49.968242Z","end":"2026-06-15T19:07:50.16629Z","steps":["trace[1182772590] 'read index received' (duration: 97.051136ms)","trace[1182772590] 'applied index is now lower than readState.Index' (duration: 100.995981ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:07:50.166326Z","caller":"traceutil/trace.go:171","msg":"trace[1078663623] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"325.910583ms","start":"2026-06-15T19:07:49.840351Z","end":"2026-06-15T19:07:50.166261Z","steps":["trace[1078663623] 'process raft request' (duration: 224.919181ms)","trace[1078663623] 'compare' (duration: 100.529025ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:07:50.1664Z","caller":"traceutil/trace.go:171","msg":"trace[797814133] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"202.482212ms","start":"2026-06-15T19:07:49.963907Z","end":"2026-06-15T19:07:50.16639Z","steps":["trace[797814133] 'process raft request' (duration: 202.33597ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:50.166403Z","caller":"traceutil/trace.go:171","msg":"trace[1566386898] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"292.456015ms","start":"2026-06-15T19:07:49.873904Z","end":"2026-06-15T19:07:50.16636Z","steps":["trace[1566386898] 'process raft request' (duration: 292.247682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:50.166486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:49.840304Z","time spent":"326.093746ms","remote":"127.0.0.1:56686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:07:50.166741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.485244ms","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-15T19:07:50.16678Z","caller":"traceutil/trace.go:171","msg":"trace[1547366061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4810; }","duration":"198.565135ms","start":"2026-06-15T19:07:49.968204Z","end":"2026-06-15T19:07:50.166769Z","steps":["trace[1547366061] 'agreement among raft nodes before linearized reading' (duration: 198.435543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:50.167041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.709061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-15T19:07:50.167094Z","caller":"traceutil/trace.go:171","msg":"trace[709495926] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4810; }","duration":"140.797522ms","start":"2026-06-15T19:07:50.026282Z","end":"2026-06-15T19:07:50.167079Z","steps":["trace[709495926] 'agreement among raft nodes before linearized reading' (duration: 140.563628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:50.167058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.06487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-15T19:07:50.167173Z","caller":"traceutil/trace.go:171","msg":"trace[1777314080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4810; }","duration":"181.230383ms","start":"2026-06-15T19:07:49.985919Z","end":"2026-06-15T19:07:50.167149Z","steps":["trace[1777314080] 'agreement among raft nodes before linearized reading' (duration: 180.808827ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:53.648339Z","caller":"traceutil/trace.go:171","msg":"trace[1758509442] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"105.18346ms","start":"2026-06-15T19:07:53.543121Z","end":"2026-06-15T19:07:53.648305Z","steps":["trace[1758509442] 'process raft request' (duration: 104.339067ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:55.408Z","caller":"traceutil/trace.go:171","msg":"trace[1284293422] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"131.516468ms","start":"2026-06-15T19:07:55.276449Z","end":"2026-06-15T19:07:55.407965Z","steps":["trace[1284293422] 'process raft request' (duration: 131.331575ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:55.518342Z","caller":"traceutil/trace.go:171","msg":"trace[1879969839] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"237.156189ms","start":"2026-06-15T19:07:55.281157Z","end":"2026-06-15T19:07:55.518313Z","steps":["trace[1879969839] 'process raft request' (duration: 236.949896ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:55.537637Z","caller":"traceutil/trace.go:171","msg":"trace[1696827577] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"109.466037ms","start":"2026-06-15T19:07:55.428155Z","end":"2026-06-15T19:07:55.537621Z","steps":["trace[1696827577] 'process raft request' (duration: 109.352475ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:57.170352Z","caller":"traceutil/trace.go:171","msg":"trace[1245871812] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"179.198725ms","start":"2026-06-15T19:07:56.991126Z","end":"2026-06-15T19:07:57.170325Z","steps":["trace[1245871812] 'process raft request' (duration: 174.989265ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:58.146152Z","caller":"traceutil/trace.go:171","msg":"trace[390531779] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"269.009673ms","start":"2026-06-15T19:07:57.877112Z","end":"2026-06-15T19:07:58.146122Z","steps":["trace[390531779] 'read index received' (duration: 268.758819ms)","trace[390531779] 'applied index is now lower than readState.Index' (duration: 249.114µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:07:58.146288Z","caller":"traceutil/trace.go:171","msg":"trace[506198123] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"269.296067ms","start":"2026-06-15T19:07:57.876982Z","end":"2026-06-15T19:07:58.146278Z","steps":["trace[506198123] 'process raft request' (duration: 268.978133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:58.146777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.633283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-szrzz\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-06-15T19:07:58.146839Z","caller":"traceutil/trace.go:171","msg":"trace[648081492] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-szrzz; range_end:; response_count:1; response_revision:4987; }","duration":"269.740335ms","start":"2026-06-15T19:07:57.877069Z","end":"2026-06-15T19:07:58.14681Z","steps":["trace[648081492] 'agreement among raft nodes before linearized reading' (duration: 269.510021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:58.147324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.393741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d6c2725-2566-4958-91f4-c176e4de10fa\" ","response":"range_response_count:1 size:4060"} {"level":"info","ts":"2026-06-15T19:07:58.147545Z","caller":"traceutil/trace.go:171","msg":"trace[1483576087] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d6c2725-2566-4958-91f4-c176e4de10fa; range_end:; response_count:1; response_revision:4987; }","duration":"196.699426ms","start":"2026-06-15T19:07:57.950818Z","end":"2026-06-15T19:07:58.147518Z","steps":["trace[1483576087] 'agreement among raft nodes before linearized reading' (duration: 196.164278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:03.736596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336103976612146,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:08:04.04511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.303282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:04.045248Z","caller":"traceutil/trace.go:171","msg":"trace[1231936261] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"861.773678ms","start":"2026-06-15T19:08:03.183448Z","end":"2026-06-15T19:08:04.045222Z","steps":["trace[1231936261] 'process raft request' (duration: 557.254313ms)","trace[1231936261] 'compare' (duration: 303.728744ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:04.045316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.183428Z","time spent":"861.854719ms","remote":"127.0.0.1:55452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:04.09227Z","caller":"traceutil/trace.go:171","msg":"trace[1772812498] linearizableReadLoop","detail":"{readStateIndex:5311; appliedIndex:5309; }","duration":"856.471292ms","start":"2026-06-15T19:08:03.235781Z","end":"2026-06-15T19:08:04.092253Z","steps":["trace[1772812498] 'read index received' (duration: 504.87935ms)","trace[1772812498] 'applied index is now lower than readState.Index' (duration: 351.590902ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:04.092403Z","caller":"traceutil/trace.go:171","msg":"trace[815881582] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"369.554471ms","start":"2026-06-15T19:08:03.722791Z","end":"2026-06-15T19:08:04.092345Z","steps":["trace[815881582] 'process raft request' (duration: 369.336228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.092593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.776727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-15T19:08:04.09265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.722747Z","time spent":"369.757793ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:04.092685Z","caller":"traceutil/trace.go:171","msg":"trace[264566062] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653; range_end:; response_count:1; response_revision:5115; }","duration":"856.914118ms","start":"2026-06-15T19:08:03.23575Z","end":"2026-06-15T19:08:04.092664Z","steps":["trace[264566062] 'agreement among raft nodes before linearized reading' (duration: 856.631514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.09272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.187393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:08:04.092749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.235741Z","time spent":"856.995419ms","remote":"127.0.0.1:56712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" "} {"level":"warn","ts":"2026-06-15T19:08:04.092631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.150196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T19:08:04.092836Z","caller":"traceutil/trace.go:171","msg":"trace[1844488259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5115; }","duration":"446.295975ms","start":"2026-06-15T19:08:03.646468Z","end":"2026-06-15T19:08:04.092764Z","steps":["trace[1844488259] 'agreement among raft nodes before linearized reading' (duration: 446.193473ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.092885Z","caller":"traceutil/trace.go:171","msg":"trace[2018428941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5115; }","duration":"624.484251ms","start":"2026-06-15T19:08:03.468379Z","end":"2026-06-15T19:08:04.092863Z","steps":["trace[2018428941] 'agreement among raft nodes before linearized reading' (duration: 624.050125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.092958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.468347Z","time spent":"624.592522ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T19:08:04.092966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.646454Z","time spent":"446.413936ms","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T19:08:04.093128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.125742ms","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-15T19:08:04.093369Z","caller":"traceutil/trace.go:171","msg":"trace[1021454253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5115; }","duration":"532.428136ms","start":"2026-06-15T19:08:03.560929Z","end":"2026-06-15T19:08:04.093357Z","steps":["trace[1021454253] 'agreement among raft nodes before linearized reading' (duration: 532.095491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.093401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.068494ms","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-15T19:08:04.093556Z","caller":"traceutil/trace.go:171","msg":"trace[1742990918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5115; }","duration":"510.287486ms","start":"2026-06-15T19:08:03.583242Z","end":"2026-06-15T19:08:04.09353Z","steps":["trace[1742990918] 'agreement among raft nodes before linearized reading' (duration: 509.605707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.093656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.583222Z","time spent":"510.422729ms","remote":"127.0.0.1:56712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-15T19:08:04.093444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:03.560891Z","time spent":"532.540807ms","remote":"127.0.0.1:56816","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-15T19:08:04.093243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.289071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-78dcc4b794-ph785.18b956b9a45abde0\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-15T19:08:04.093858Z","caller":"traceutil/trace.go:171","msg":"trace[1404277425] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-78dcc4b794-ph785.18b956b9a45abde0; range_end:; response_count:1; response_revision:5115; }","duration":"136.932021ms","start":"2026-06-15T19:08:03.956894Z","end":"2026-06-15T19:08:04.093826Z","steps":["trace[1404277425] 'agreement among raft nodes before linearized reading' (duration: 136.28615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.093099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.910901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-15T19:08:04.094062Z","caller":"traceutil/trace.go:171","msg":"trace[1932114102] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653; range_end:; response_count:1; response_revision:5115; }","duration":"137.947966ms","start":"2026-06-15T19:08:03.956101Z","end":"2026-06-15T19:08:04.094049Z","steps":["trace[1932114102] 'agreement among raft nodes before linearized reading' (duration: 136.549305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.094624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.567075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-15T19:08:04.094762Z","caller":"traceutil/trace.go:171","msg":"trace[1697946176] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5115; }","duration":"113.757967ms","start":"2026-06-15T19:08:03.980973Z","end":"2026-06-15T19:08:04.09473Z","steps":["trace[1697946176] 'agreement among raft nodes before linearized reading' (duration: 112.947176ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.262525Z","caller":"traceutil/trace.go:171","msg":"trace[895584781] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"164.427166ms","start":"2026-06-15T19:08:04.098079Z","end":"2026-06-15T19:08:04.262507Z","steps":["trace[895584781] 'process raft request' (duration: 164.325245ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.262584Z","caller":"traceutil/trace.go:171","msg":"trace[1530434861] linearizableReadLoop","detail":"{readStateIndex:5312; appliedIndex:5312; }","duration":"162.370187ms","start":"2026-06-15T19:08:04.100167Z","end":"2026-06-15T19:08:04.262537Z","steps":["trace[1530434861] 'read index received' (duration: 162.328186ms)","trace[1530434861] 'applied index is now lower than readState.Index' (duration: 36.69µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:04.263021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.816423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b80a7d53-825a-4887-9464-fb2b3b95ba5d\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-06-15T19:08:04.263176Z","caller":"traceutil/trace.go:171","msg":"trace[625758853] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b80a7d53-825a-4887-9464-fb2b3b95ba5d; range_end:; response_count:1; response_revision:5116; }","duration":"163.004805ms","start":"2026-06-15T19:08:04.100143Z","end":"2026-06-15T19:08:04.263148Z","steps":["trace[625758853] 'agreement among raft nodes before linearized reading' (duration: 162.597459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:04.289498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.166142ms","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-15T19:08:04.289533Z","caller":"traceutil/trace.go:171","msg":"trace[396658738] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5119; }","duration":"189.225442ms","start":"2026-06-15T19:08:04.1003Z","end":"2026-06-15T19:08:04.289525Z","steps":["trace[396658738] 'agreement among raft nodes before linearized reading' (duration: 189.09156ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.289691Z","caller":"traceutil/trace.go:171","msg":"trace[118011817] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"189.14344ms","start":"2026-06-15T19:08:04.100541Z","end":"2026-06-15T19:08:04.289684Z","steps":["trace[118011817] 'process raft request' (duration: 188.673964ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.289858Z","caller":"traceutil/trace.go:171","msg":"trace[995820279] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"188.240398ms","start":"2026-06-15T19:08:04.101607Z","end":"2026-06-15T19:08:04.289847Z","steps":["trace[995820279] 'process raft request' (duration: 187.72127ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.28986Z","caller":"traceutil/trace.go:171","msg":"trace[1732143951] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"186.807477ms","start":"2026-06-15T19:08:04.103015Z","end":"2026-06-15T19:08:04.289822Z","steps":["trace[1732143951] 'process raft request' (duration: 186.338781ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:04.774376Z","caller":"traceutil/trace.go:171","msg":"trace[719467606] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"146.420046ms","start":"2026-06-15T19:08:04.627934Z","end":"2026-06-15T19:08:04.774354Z","steps":["trace[719467606] 'process raft request' (duration: 120.209639ms)","trace[719467606] 'compare' (duration: 25.988324ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:08.266695Z","caller":"traceutil/trace.go:171","msg":"trace[1488448722] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5363; }","duration":"149.049346ms","start":"2026-06-15T19:08:08.117623Z","end":"2026-06-15T19:08:08.266673Z","steps":["trace[1488448722] 'read index received' (duration: 145.80718ms)","trace[1488448722] 'applied index is now lower than readState.Index' (duration: 3.240986ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:08.266818Z","caller":"traceutil/trace.go:171","msg":"trace[325044786] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"158.070436ms","start":"2026-06-15T19:08:08.108736Z","end":"2026-06-15T19:08:08.266806Z","steps":["trace[325044786] 'process raft request' (duration: 154.714648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:08.267174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.538973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-06-15T19:08:08.267222Z","caller":"traceutil/trace.go:171","msg":"trace[1941193478] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cc450ce-98cf-410f-af6e-4636676ed653; range_end:; response_count:1; response_revision:5168; }","duration":"149.618505ms","start":"2026-06-15T19:08:08.117589Z","end":"2026-06-15T19:08:08.267208Z","steps":["trace[1941193478] 'agreement among raft nodes before linearized reading' (duration: 149.488133ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:18.567503Z","caller":"traceutil/trace.go:171","msg":"trace[1418210664] linearizableReadLoop","detail":"{readStateIndex:5493; appliedIndex:5492; }","duration":"377.609769ms","start":"2026-06-15T19:08:18.189857Z","end":"2026-06-15T19:08:18.567467Z","steps":["trace[1418210664] 'read index received' (duration: 377.326585ms)","trace[1418210664] 'applied index is now lower than readState.Index' (duration: 281.664µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:18.567588Z","caller":"traceutil/trace.go:171","msg":"trace[1886341009] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"452.391362ms","start":"2026-06-15T19:08:18.115153Z","end":"2026-06-15T19:08:18.567545Z","steps":["trace[1886341009] 'process raft request' (duration: 452.083287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.568108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.227767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-15T19:08:18.568195Z","caller":"traceutil/trace.go:171","msg":"trace[1032753877] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5292; }","duration":"378.353249ms","start":"2026-06-15T19:08:18.189824Z","end":"2026-06-15T19:08:18.568177Z","steps":["trace[1032753877] 'agreement among raft nodes before linearized reading' (duration: 377.787542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.56824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.189807Z","time spent":"378.42605ms","remote":"127.0.0.1:56680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-15T19:08:18.568284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.115123Z","time spent":"452.709126ms","remote":"127.0.0.1:56712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.987715Z","caller":"traceutil/trace.go:171","msg":"trace[533481284] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"686.594642ms","start":"2026-06-15T19:08:18.30107Z","end":"2026-06-15T19:08:18.987664Z","steps":["trace[533481284] 'process raft request' (duration: 662.066443ms)","trace[533481284] 'compare' (duration: 23.823709ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:18.987995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.301035Z","time spent":"686.819015ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.988435Z","caller":"traceutil/trace.go:171","msg":"trace[647321351] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"631.050762ms","start":"2026-06-15T19:08:18.357365Z","end":"2026-06-15T19:08:18.988416Z","steps":["trace[647321351] 'process raft request' (duration: 630.139689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.988532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.357333Z","time spent":"631.153214ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.995099Z","caller":"traceutil/trace.go:171","msg":"trace[1228606907] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"635.385854ms","start":"2026-06-15T19:08:18.35969Z","end":"2026-06-15T19:08:18.995076Z","steps":["trace[1228606907] 'process raft request' (duration: 635.301792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.995242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.359634Z","time spent":"635.524976ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.995348Z","caller":"traceutil/trace.go:171","msg":"trace[455736883] linearizableReadLoop","detail":"{readStateIndex:5497; appliedIndex:5493; }","duration":"427.734402ms","start":"2026-06-15T19:08:18.567592Z","end":"2026-06-15T19:08:18.995326Z","steps":["trace[455736883] 'read index received' (duration: 395.447413ms)","trace[455736883] 'applied index is now lower than readState.Index' (duration: 32.284279ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:18.995408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.188875ms","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-15T19:08:18.995441Z","caller":"traceutil/trace.go:171","msg":"trace[89380323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5295; }","duration":"591.232386ms","start":"2026-06-15T19:08:18.404198Z","end":"2026-06-15T19:08:18.99543Z","steps":["trace[89380323] 'agreement among raft nodes before linearized reading' (duration: 591.131694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.995469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.404191Z","time spent":"591.271076ms","remote":"127.0.0.1:56816","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":"warn","ts":"2026-06-15T19:08:18.99557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.326179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T19:08:18.995576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.210798ms","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-15T19:08:18.995594Z","caller":"traceutil/trace.go:171","msg":"trace[1746759585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5295; }","duration":"627.39319ms","start":"2026-06-15T19:08:18.368194Z","end":"2026-06-15T19:08:18.995587Z","steps":["trace[1746759585] 'agreement among raft nodes before linearized reading' (duration: 627.331709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.995617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.36818Z","time spent":"627.42852ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T19:08:18.995611Z","caller":"traceutil/trace.go:171","msg":"trace[701826519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5295; }","duration":"625.29807ms","start":"2026-06-15T19:08:18.370299Z","end":"2026-06-15T19:08:18.995597Z","steps":["trace[701826519] 'agreement among raft nodes before linearized reading' (duration: 625.154718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.995664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.370272Z","time spent":"625.384571ms","remote":"127.0.0.1:56816","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":"info","ts":"2026-06-15T19:08:18.996064Z","caller":"traceutil/trace.go:171","msg":"trace[782707923] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"422.829912ms","start":"2026-06-15T19:08:18.573225Z","end":"2026-06-15T19:08:18.996055Z","steps":["trace[782707923] 'process raft request' (duration: 422.466487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.99612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.573208Z","time spent":"422.885673ms","remote":"127.0.0.1:56686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.996326Z","caller":"traceutil/trace.go:171","msg":"trace[1578042686] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"420.877784ms","start":"2026-06-15T19:08:18.575438Z","end":"2026-06-15T19:08:18.996316Z","steps":["trace[1578042686] 'process raft request' (duration: 420.345006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.043675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-06-15T19:08:18.996434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.575416Z","time spent":"420.939284ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:18.996447Z","caller":"traceutil/trace.go:171","msg":"trace[640581314] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2cc450ce-98cf-410f-af6e-4636676ed653; range_end:; response_count:1; response_revision:5299; }","duration":"423.101765ms","start":"2026-06-15T19:08:18.573336Z","end":"2026-06-15T19:08:18.996438Z","steps":["trace[640581314] 'agreement among raft nodes before linearized reading' (duration: 423.008604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.573331Z","time spent":"423.134556ms","remote":"127.0.0.1:56666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-2cc450ce-98cf-410f-af6e-4636676ed653\" "} {"level":"warn","ts":"2026-06-15T19:08:18.996644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.885283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:08:18.996683Z","caller":"traceutil/trace.go:171","msg":"trace[1826482916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5299; }","duration":"304.921704ms","start":"2026-06-15T19:08:18.69175Z","end":"2026-06-15T19:08:18.996672Z","steps":["trace[1826482916] 'agreement among raft nodes before linearized reading' (duration: 304.866523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.01163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:08:18.99671Z","caller":"traceutil/trace.go:171","msg":"trace[1507807387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5299; }","duration":"384.04943ms","start":"2026-06-15T19:08:18.612653Z","end":"2026-06-15T19:08:18.996702Z","steps":["trace[1507807387] 'agreement among raft nodes before linearized reading' (duration: 383.989529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.691683Z","time spent":"305.016045ms","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T19:08:18.996781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.262854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-15T19:08:18.996799Z","caller":"traceutil/trace.go:171","msg":"trace[1586052225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5299; }","duration":"394.297815ms","start":"2026-06-15T19:08:18.602497Z","end":"2026-06-15T19:08:18.996795Z","steps":["trace[1586052225] 'agreement among raft nodes before linearized reading' (duration: 394.245155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.602488Z","time spent":"394.323876ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T19:08:18.996886Z","caller":"traceutil/trace.go:171","msg":"trace[1971842876] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"420.925745ms","start":"2026-06-15T19:08:18.57595Z","end":"2026-06-15T19:08:18.996876Z","steps":["trace[1971842876] 'process raft request' (duration: 420.058513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.525094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:08:18.99694Z","caller":"traceutil/trace.go:171","msg":"trace[846930304] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5299; }","duration":"419.569674ms","start":"2026-06-15T19:08:18.577363Z","end":"2026-06-15T19:08:18.996933Z","steps":["trace[846930304] 'agreement among raft nodes before linearized reading' (duration: 419.531754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.996972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.577355Z","time spent":"419.611585ms","remote":"127.0.0.1:58084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:08:18.996978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.57594Z","time spent":"420.992805ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:08:18.996732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.612643Z","time spent":"384.08026ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-15T19:08:18.997737Z","caller":"traceutil/trace.go:171","msg":"trace[1844363826] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"419.290451ms","start":"2026-06-15T19:08:18.578437Z","end":"2026-06-15T19:08:18.997727Z","steps":["trace[1844363826] 'process raft request' (duration: 417.871301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:18.998228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:18.578397Z","time spent":"419.368813ms","remote":"127.0.0.1:56686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:19.172998Z","caller":"traceutil/trace.go:171","msg":"trace[1657521084] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"169.627359ms","start":"2026-06-15T19:08:19.003341Z","end":"2026-06-15T19:08:19.172969Z","steps":["trace[1657521084] 'process raft request' (duration: 131.675221ms)","trace[1657521084] 'compare' (duration: 37.102587ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:19.173318Z","caller":"traceutil/trace.go:171","msg":"trace[1752468614] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"168.840808ms","start":"2026-06-15T19:08:19.004454Z","end":"2026-06-15T19:08:19.173295Z","steps":["trace[1752468614] 'process raft request' (duration: 168.203179ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:19.173359Z","caller":"traceutil/trace.go:171","msg":"trace[1113029738] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"169.581879ms","start":"2026-06-15T19:08:19.003765Z","end":"2026-06-15T19:08:19.173347Z","steps":["trace[1113029738] 'process raft request' (duration: 168.783398ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:19.173393Z","caller":"traceutil/trace.go:171","msg":"trace[1956064056] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"169.70485ms","start":"2026-06-15T19:08:19.003652Z","end":"2026-06-15T19:08:19.173357Z","steps":["trace[1956064056] 'process raft request' (duration: 168.763357ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:19.173443Z","caller":"traceutil/trace.go:171","msg":"trace[1209937639] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"169.248464ms","start":"2026-06-15T19:08:19.004182Z","end":"2026-06-15T19:08:19.17343Z","steps":["trace[1209937639] 'process raft request' (duration: 168.442723ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:19.173374Z","caller":"traceutil/trace.go:171","msg":"trace[1147344777] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"169.541658ms","start":"2026-06-15T19:08:19.003768Z","end":"2026-06-15T19:08:19.17331Z","steps":["trace[1147344777] 'process raft request' (duration: 168.819858ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:19.765571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2812} {"level":"info","ts":"2026-06-15T19:08:19.765727Z","caller":"traceutil/trace.go:171","msg":"trace[497862438] compact","detail":"{revision:2812; response_revision:5306; }","duration":"114.562317ms","start":"2026-06-15T19:08:19.651128Z","end":"2026-06-15T19:08:19.76569Z","steps":["trace[497862438] 'process raft request' (duration: 27.202316ms)","trace[497862438] 'check and update compact revision' (duration: 86.787213ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:20.010072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.835781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10561"} {"level":"info","ts":"2026-06-15T19:08:20.010169Z","caller":"traceutil/trace.go:171","msg":"trace[41153693] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5306; }","duration":"233.959573ms","start":"2026-06-15T19:08:19.776186Z","end":"2026-06-15T19:08:20.010146Z","steps":["trace[41153693] 'range keys from in-memory index tree' (duration: 233.611968ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:20.223363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2812,"took":"456.449672ms","hash":1930503493,"current-db-size-bytes":52264960,"current-db-size":"52 MB","current-db-size-in-use-bytes":27398144,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-15T19:08:20.223481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1930503493,"revision":2812,"compact-revision":-1} {"level":"warn","ts":"2026-06-15T19:08:57.694882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.48374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:57.695184Z","caller":"traceutil/trace.go:171","msg":"trace[585455126] linearizableReadLoop","detail":"{readStateIndex:5853; appliedIndex:5848; }","duration":"255.508985ms","start":"2026-06-15T19:08:57.439654Z","end":"2026-06-15T19:08:57.695163Z","steps":["trace[585455126] 'read index received' (duration: 78.958083ms)","trace[585455126] 'applied index is now lower than readState.Index' (duration: 176.549842ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:57.6953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.654237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:08:57.695334Z","caller":"traceutil/trace.go:171","msg":"trace[571183938] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5642; }","duration":"255.734568ms","start":"2026-06-15T19:08:57.439585Z","end":"2026-06-15T19:08:57.695319Z","steps":["trace[571183938] 'agreement among raft nodes before linearized reading' (duration: 255.634827ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:57.695665Z","caller":"traceutil/trace.go:171","msg":"trace[1684692142] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"264.381808ms","start":"2026-06-15T19:08:57.431269Z","end":"2026-06-15T19:08:57.69565Z","steps":["trace[1684692142] 'process raft request' (duration: 103.026645ms)","trace[1684692142] 'compare' (duration: 159.932513ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:57.695796Z","caller":"traceutil/trace.go:171","msg":"trace[1865440763] transaction","detail":"{read_only:false; response_revision:5640; number_of_response:1; }","duration":"264.384378ms","start":"2026-06-15T19:08:57.431402Z","end":"2026-06-15T19:08:57.695786Z","steps":["trace[1865440763] 'process raft request' (duration: 263.600187ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:57.695989Z","caller":"traceutil/trace.go:171","msg":"trace[1787503375] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"263.781928ms","start":"2026-06-15T19:08:57.432196Z","end":"2026-06-15T19:08:57.695978Z","steps":["trace[1787503375] 'process raft request' (duration: 262.873806ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:57.696154Z","caller":"traceutil/trace.go:171","msg":"trace[1952508856] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"263.498094ms","start":"2026-06-15T19:08:57.432646Z","end":"2026-06-15T19:08:57.696144Z","steps":["trace[1952508856] 'process raft request' (duration: 262.475371ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:57.81638Z","caller":"traceutil/trace.go:171","msg":"trace[1494445535] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"111.755446ms","start":"2026-06-15T19:08:57.704589Z","end":"2026-06-15T19:08:57.816344Z","steps":["trace[1494445535] 'process raft request' (duration: 111.416762ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:22.011085Z","caller":"traceutil/trace.go:171","msg":"trace[2030245296] linearizableReadLoop","detail":"{readStateIndex:6010; appliedIndex:6008; }","duration":"116.44828ms","start":"2026-06-15T19:09:21.894614Z","end":"2026-06-15T19:09:22.011062Z","steps":["trace[2030245296] 'read index received' (duration: 63.179153ms)","trace[2030245296] 'applied index is now lower than readState.Index' (duration: 53.264617ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:22.011227Z","caller":"traceutil/trace.go:171","msg":"trace[975964306] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"116.633322ms","start":"2026-06-15T19:09:21.894573Z","end":"2026-06-15T19:09:22.011206Z","steps":["trace[975964306] 'process raft request' (duration: 94.711123ms)","trace[975964306] 'compare' (duration: 21.584815ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:22.011264Z","caller":"traceutil/trace.go:171","msg":"trace[2068949002] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"114.674966ms","start":"2026-06-15T19:09:21.896576Z","end":"2026-06-15T19:09:22.011251Z","steps":["trace[2068949002] 'process raft request' (duration: 114.421242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:22.011492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.934706ms","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-15T19:09:22.011534Z","caller":"traceutil/trace.go:171","msg":"trace[156822135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5794; }","duration":"117.013407ms","start":"2026-06-15T19:09:21.894508Z","end":"2026-06-15T19:09:22.011521Z","steps":["trace[156822135] 'agreement among raft nodes before linearized reading' (duration: 116.842055ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:53.993841Z","caller":"traceutil/trace.go:171","msg":"trace[1158221035] transaction","detail":"{read_only:false; response_revision:6022; number_of_response:1; }","duration":"118.021588ms","start":"2026-06-15T19:09:53.875795Z","end":"2026-06-15T19:09:53.993816Z","steps":["trace[1158221035] 'process raft request' (duration: 117.864366ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.350083Z","caller":"traceutil/trace.go:171","msg":"trace[772286388] transaction","detail":"{read_only:false; response_revision:6027; number_of_response:1; }","duration":"128.68932ms","start":"2026-06-15T19:09:54.221356Z","end":"2026-06-15T19:09:54.350046Z","steps":["trace[772286388] 'process raft request' (duration: 43.498944ms)","trace[772286388] 'compare' (duration: 85.017674ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:54.365297Z","caller":"traceutil/trace.go:171","msg":"trace[565545620] transaction","detail":"{read_only:false; response_revision:6029; number_of_response:1; }","duration":"142.635628ms","start":"2026-06-15T19:09:54.222633Z","end":"2026-06-15T19:09:54.365269Z","steps":["trace[565545620] 'process raft request' (duration: 142.541577ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.365309Z","caller":"traceutil/trace.go:171","msg":"trace[1915319455] transaction","detail":"{read_only:false; response_revision:6028; number_of_response:1; }","duration":"143.054874ms","start":"2026-06-15T19:09:54.222229Z","end":"2026-06-15T19:09:54.365284Z","steps":["trace[1915319455] 'process raft request' (duration: 142.829741ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.365714Z","caller":"traceutil/trace.go:171","msg":"trace[1073214545] transaction","detail":"{read_only:false; response_revision:6031; number_of_response:1; }","duration":"111.951825ms","start":"2026-06-15T19:09:54.253749Z","end":"2026-06-15T19:09:54.365701Z","steps":["trace[1073214545] 'process raft request' (duration: 111.787263ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.365928Z","caller":"traceutil/trace.go:171","msg":"trace[1053137691] transaction","detail":"{read_only:false; response_revision:6030; number_of_response:1; }","duration":"142.681829ms","start":"2026-06-15T19:09:54.223023Z","end":"2026-06-15T19:09:54.365705Z","steps":["trace[1053137691] 'process raft request' (duration: 142.199393ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.546218Z","caller":"traceutil/trace.go:171","msg":"trace[651991948] transaction","detail":"{read_only:false; response_revision:6033; number_of_response:1; }","duration":"108.59703ms","start":"2026-06-15T19:09:54.437593Z","end":"2026-06-15T19:09:54.54619Z","steps":["trace[651991948] 'process raft request' (duration: 108.377538ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:54.699921Z","caller":"traceutil/trace.go:171","msg":"trace[1419226673] transaction","detail":"{read_only:false; response_revision:6035; number_of_response:1; }","duration":"147.374313ms","start":"2026-06-15T19:09:54.552482Z","end":"2026-06-15T19:09:54.699856Z","steps":["trace[1419226673] 'process raft request' (duration: 100.37505ms)","trace[1419226673] 'compare' (duration: 46.664708ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:54.700086Z","caller":"traceutil/trace.go:171","msg":"trace[884657662] transaction","detail":"{read_only:false; response_revision:6036; number_of_response:1; }","duration":"144.350441ms","start":"2026-06-15T19:09:54.555635Z","end":"2026-06-15T19:09:54.699986Z","steps":["trace[884657662] 'process raft request' (duration: 144.124618ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:55.557319Z","caller":"traceutil/trace.go:171","msg":"trace[1197982798] transaction","detail":"{read_only:false; response_revision:6041; number_of_response:1; }","duration":"115.083146ms","start":"2026-06-15T19:09:55.442208Z","end":"2026-06-15T19:09:55.557291Z","steps":["trace[1197982798] 'process raft request' (duration: 114.873914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:56.592959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.759972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-15T19:09:56.593066Z","caller":"traceutil/trace.go:171","msg":"trace[1844028041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6048; }","duration":"222.901095ms","start":"2026-06-15T19:09:56.370137Z","end":"2026-06-15T19:09:56.593038Z","steps":["trace[1844028041] 'range keys from in-memory index tree' (duration: 222.608401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:56.592959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.326102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-15T19:09:56.593151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.608691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-15T19:09:56.593164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.031463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-15T19:09:56.593223Z","caller":"traceutil/trace.go:171","msg":"trace[1697634758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6048; }","duration":"222.703653ms","start":"2026-06-15T19:09:56.370503Z","end":"2026-06-15T19:09:56.593207Z","steps":["trace[1697634758] 'range keys from in-memory index tree' (duration: 222.468188ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.593156Z","caller":"traceutil/trace.go:171","msg":"trace[1869268150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6048; }","duration":"238.589827ms","start":"2026-06-15T19:09:56.354543Z","end":"2026-06-15T19:09:56.593133Z","steps":["trace[1869268150] 'range keys from in-memory index tree' (duration: 238.15206ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.593248Z","caller":"traceutil/trace.go:171","msg":"trace[259496208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6048; }","duration":"222.382738ms","start":"2026-06-15T19:09:56.370848Z","end":"2026-06-15T19:09:56.593231Z","steps":["trace[259496208] 'range keys from in-memory index tree' (duration: 221.87286ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.825292Z","caller":"traceutil/trace.go:171","msg":"trace[1102660660] transaction","detail":"{read_only:false; response_revision:6050; number_of_response:1; }","duration":"227.131641ms","start":"2026-06-15T19:09:56.59814Z","end":"2026-06-15T19:09:56.825271Z","steps":["trace[1102660660] 'process raft request' (duration: 226.986369ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.82567Z","caller":"traceutil/trace.go:171","msg":"trace[172182292] transaction","detail":"{read_only:false; response_revision:6051; number_of_response:1; }","duration":"227.153252ms","start":"2026-06-15T19:09:56.598488Z","end":"2026-06-15T19:09:56.825642Z","steps":["trace[172182292] 'process raft request' (duration: 226.749046ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.825854Z","caller":"traceutil/trace.go:171","msg":"trace[107605745] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"226.937469ms","start":"2026-06-15T19:09:56.598905Z","end":"2026-06-15T19:09:56.825843Z","steps":["trace[107605745] 'process raft request' (duration: 226.669094ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:56.825807Z","caller":"traceutil/trace.go:171","msg":"trace[1515305274] linearizableReadLoop","detail":"{readStateIndex:6276; appliedIndex:6273; }","duration":"178.155344ms","start":"2026-06-15T19:09:56.647611Z","end":"2026-06-15T19:09:56.825766Z","steps":["trace[1515305274] 'read index received' (duration: 177.538015ms)","trace[1515305274] 'applied index is now lower than readState.Index' (duration: 616.059µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:56.825969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.314936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:09:56.826049Z","caller":"traceutil/trace.go:171","msg":"trace[2111778433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6052; }","duration":"178.465257ms","start":"2026-06-15T19:09:56.647569Z","end":"2026-06-15T19:09:56.826035Z","steps":["trace[2111778433] 'agreement among raft nodes before linearized reading' (duration: 178.313185ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:57.519428Z","caller":"traceutil/trace.go:171","msg":"trace[157825707] transaction","detail":"{read_only:false; response_revision:6060; number_of_response:1; }","duration":"129.730852ms","start":"2026-06-15T19:09:57.389658Z","end":"2026-06-15T19:09:57.519388Z","steps":["trace[157825707] 'process raft request' (duration: 66.231209ms)","trace[157825707] 'compare' (duration: 63.307031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:12:03.285434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.144609ms","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-15T19:12:03.285614Z","caller":"traceutil/trace.go:171","msg":"trace[1674851182] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:6920; }","duration":"279.392477ms","start":"2026-06-15T19:12:03.006183Z","end":"2026-06-15T19:12:03.285575Z","steps":["trace[1674851182] 'count revisions from in-memory index tree' (duration: 278.983134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.285616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.158659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T19:12:03.285748Z","caller":"traceutil/trace.go:171","msg":"trace[434664933] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:6920; }","duration":"241.339315ms","start":"2026-06-15T19:12:03.04438Z","end":"2026-06-15T19:12:03.285719Z","steps":["trace[434664933] 'range keys from in-memory index tree' (duration: 240.939073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.285633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.019927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T19:12:03.285822Z","caller":"traceutil/trace.go:171","msg":"trace[2084910305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6920; }","duration":"329.250044ms","start":"2026-06-15T19:12:02.956546Z","end":"2026-06-15T19:12:03.285796Z","steps":["trace[2084910305] 'range keys from in-memory index tree' (duration: 328.830652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.285879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:02.956531Z","time spent":"329.338537ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T19:12:03.572611Z","caller":"traceutil/trace.go:171","msg":"trace[611726898] linearizableReadLoop","detail":"{readStateIndex:7175; appliedIndex:7173; }","duration":"264.224791ms","start":"2026-06-15T19:12:03.308358Z","end":"2026-06-15T19:12:03.572583Z","steps":["trace[611726898] 'read index received' (duration: 239.788609ms)","trace[611726898] 'applied index is now lower than readState.Index' (duration: 24.433772ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:03.572766Z","caller":"traceutil/trace.go:171","msg":"trace[1151128892] transaction","detail":"{read_only:false; response_revision:6921; number_of_response:1; }","duration":"282.146148ms","start":"2026-06-15T19:12:03.290586Z","end":"2026-06-15T19:12:03.572732Z","steps":["trace[1151128892] 'process raft request' (duration: 281.807848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.573437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.049726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-15T19:12:03.573664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.374777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-15T19:12:03.573723Z","caller":"traceutil/trace.go:171","msg":"trace[1929140037] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6922; }","duration":"244.489271ms","start":"2026-06-15T19:12:03.329219Z","end":"2026-06-15T19:12:03.573709Z","steps":["trace[1929140037] 'agreement among raft nodes before linearized reading' (duration: 244.251024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.573802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.348879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-15T19:12:03.573876Z","caller":"traceutil/trace.go:171","msg":"trace[1056616393] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6922; }","duration":"107.493333ms","start":"2026-06-15T19:12:03.466364Z","end":"2026-06-15T19:12:03.573857Z","steps":["trace[1056616393] 'agreement among raft nodes before linearized reading' (duration: 107.280837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:03.573802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.642286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:12:03.574055Z","caller":"traceutil/trace.go:171","msg":"trace[724553184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6922; }","duration":"183.945845ms","start":"2026-06-15T19:12:03.390087Z","end":"2026-06-15T19:12:03.574033Z","steps":["trace[724553184] 'agreement among raft nodes before linearized reading' (duration: 183.595985ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:03.57496Z","caller":"traceutil/trace.go:171","msg":"trace[1745003875] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:6921; }","duration":"265.204821ms","start":"2026-06-15T19:12:03.308308Z","end":"2026-06-15T19:12:03.573513Z","steps":["trace[1745003875] 'agreement among raft nodes before linearized reading' (duration: 264.481688ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:07.822117Z","caller":"traceutil/trace.go:171","msg":"trace[1707759505] transaction","detail":"{read_only:false; response_revision:6944; number_of_response:1; }","duration":"132.605834ms","start":"2026-06-15T19:12:07.689477Z","end":"2026-06-15T19:12:07.822083Z","steps":["trace[1707759505] 'process raft request' (duration: 132.544912ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:07.82241Z","caller":"traceutil/trace.go:171","msg":"trace[2120919915] transaction","detail":"{read_only:false; response_revision:6943; number_of_response:1; }","duration":"132.894242ms","start":"2026-06-15T19:12:07.689476Z","end":"2026-06-15T19:12:07.82237Z","steps":["trace[2120919915] 'process raft request' (duration: 109.423034ms)","trace[2120919915] 'compare' (duration: 22.761107ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:07.838569Z","caller":"traceutil/trace.go:171","msg":"trace[1313624913] transaction","detail":"{read_only:false; response_revision:6945; number_of_response:1; }","duration":"121.310668ms","start":"2026-06-15T19:12:07.717227Z","end":"2026-06-15T19:12:07.838537Z","steps":["trace[1313624913] 'process raft request' (duration: 120.810144ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:09.046674Z","caller":"traceutil/trace.go:171","msg":"trace[135089444] transaction","detail":"{read_only:false; response_revision:6953; number_of_response:1; }","duration":"129.866762ms","start":"2026-06-15T19:12:08.916767Z","end":"2026-06-15T19:12:09.046634Z","steps":["trace[135089444] 'process raft request' (duration: 129.621105ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:42.330849Z","caller":"traceutil/trace.go:171","msg":"trace[452177207] transaction","detail":"{read_only:false; response_revision:7177; number_of_response:1; }","duration":"125.060234ms","start":"2026-06-15T19:12:42.205762Z","end":"2026-06-15T19:12:42.330822Z","steps":["trace[452177207] 'process raft request' (duration: 124.778546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:13:02.633065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.423793ms","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-15T19:13:02.633217Z","caller":"traceutil/trace.go:171","msg":"trace[302099520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7302; }","duration":"192.717382ms","start":"2026-06-15T19:13:02.440466Z","end":"2026-06-15T19:13:02.633183Z","steps":["trace[302099520] 'range keys from in-memory index tree' (duration: 192.118055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:13:02.633209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.616148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-15T19:13:02.633231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.185537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:13:02.633328Z","caller":"traceutil/trace.go:171","msg":"trace[645486432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7302; }","duration":"192.756342ms","start":"2026-06-15T19:13:02.440529Z","end":"2026-06-15T19:13:02.633285Z","steps":["trace[645486432] 'range keys from in-memory index tree' (duration: 192.392702ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:13:02.633346Z","caller":"traceutil/trace.go:171","msg":"trace[1014648370] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7302; }","duration":"189.32577ms","start":"2026-06-15T19:13:02.443986Z","end":"2026-06-15T19:13:02.633312Z","steps":["trace[1014648370] 'range keys from in-memory index tree' (duration: 188.883729ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:13:02.633513Z","caller":"traceutil/trace.go:171","msg":"trace[2057583862] transaction","detail":"{read_only:false; response_revision:7303; number_of_response:1; }","duration":"170.007881ms","start":"2026-06-15T19:13:02.463492Z","end":"2026-06-15T19:13:02.633499Z","steps":["trace[2057583862] 'process raft request' (duration: 82.588631ms)","trace[2057583862] 'compare' (duration: 86.853025ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:13:19.77536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5306} {"level":"info","ts":"2026-06-15T19:13:19.871475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5306,"took":"94.731443ms","hash":4222187093,"current-db-size-bytes":52264960,"current-db-size":"52 MB","current-db-size-in-use-bytes":19083264,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-15T19:13:19.871568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4222187093,"revision":5306,"compact-revision":2812}