{"level":"warn","ts":"2026-05-21T10:41:18.77201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T10:41:18.772163Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T10:41:18.77229Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T10:41:18.772314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-21T10:41:18.772352Z","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-05-21T10:41:18.773551Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-21T10:41:18.773699Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T10:41:18.779155Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.038223ms"} {"level":"info","ts":"2026-05-21T10:41:18.784845Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-21T10:41:18.784947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T10:41:18.784976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-21T10:41:18.784993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T10:41:18.785006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-21T10:41:18.785062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-21T10:41:18.789304Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T10:41:18.791034Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T10:41:18.792114Z","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-05-21T10:41:18.794465Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T10:41:18.794679Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T10:41:18.794754Z","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-05-21T10:41:18.794891Z","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-05-21T10:41:18.794905Z","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-05-21T10:41:18.796657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-21T10:41:18.79782Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-21T10:41:18.800006Z","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-05-21T10:41:18.800114Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-21T10:41:18.800174Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-21T10:41:18.800501Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T10:41:18.800565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T10:41:19.285733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T10:41:19.285788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T10:41:19.285817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-21T10:41:19.285833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-21T10:41:19.28584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-21T10:41:19.285851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-21T10:41:19.285862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-21T10:41:19.302512Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:41:19.303573Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T10:41:19.30358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T10:41:19.303811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T10:41:19.303945Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T10:41:19.303997Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T10:41:19.304754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:41:19.304872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:41:19.304909Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:41:19.307266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-21T10:41:19.308309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T10:41:59.270966Z","caller":"traceutil/trace.go:171","msg":"trace[1136812784] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"226.619814ms","start":"2026-05-21T10:41:59.04432Z","end":"2026-05-21T10:41:59.27094Z","steps":["trace[1136812784] 'process raft request' (duration: 226.48018ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:41:59.300191Z","caller":"traceutil/trace.go:171","msg":"trace[649687102] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"138.948211ms","start":"2026-05-21T10:41:59.161222Z","end":"2026-05-21T10:41:59.300171Z","steps":["trace[649687102] 'read index received' (duration: 109.538282ms)","trace[649687102] 'applied index is now lower than readState.Index' (duration: 29.408859ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:41:59.300254Z","caller":"traceutil/trace.go:171","msg":"trace[1619824509] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"215.850314ms","start":"2026-05-21T10:41:59.084351Z","end":"2026-05-21T10:41:59.300202Z","steps":["trace[1619824509] 'process raft request' (duration: 214.680132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:41:59.300487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.160587ms","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-05-21T10:41:59.300563Z","caller":"traceutil/trace.go:171","msg":"trace[991112841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:610; }","duration":"139.301802ms","start":"2026-05-21T10:41:59.161247Z","end":"2026-05-21T10:41:59.300549Z","steps":["trace[991112841] 'agreement among raft nodes before linearized reading' (duration: 139.139116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:41:59.300561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.940908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T10:41:59.300529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.267971ms","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-05-21T10:41:59.300689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.626635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:41:59.300637Z","caller":"traceutil/trace.go:171","msg":"trace[1617133190] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:610; }","duration":"122.077232ms","start":"2026-05-21T10:41:59.178547Z","end":"2026-05-21T10:41:59.300625Z","steps":["trace[1617133190] 'agreement among raft nodes before linearized reading' (duration: 121.963709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:41:59.300771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.781969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:41:59.300812Z","caller":"traceutil/trace.go:171","msg":"trace[1289122036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:610; }","duration":"123.835732ms","start":"2026-05-21T10:41:59.176967Z","end":"2026-05-21T10:41:59.300802Z","steps":["trace[1289122036] 'agreement among raft nodes before linearized reading' (duration: 123.77735ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:41:59.3009Z","caller":"traceutil/trace.go:171","msg":"trace[447736609] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:610; }","duration":"139.548238ms","start":"2026-05-21T10:41:59.161192Z","end":"2026-05-21T10:41:59.300741Z","steps":["trace[447736609] 'agreement among raft nodes before linearized reading' (duration: 139.156106ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:41:59.300975Z","caller":"traceutil/trace.go:171","msg":"trace[765007139] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:610; }","duration":"123.753959ms","start":"2026-05-21T10:41:59.177Z","end":"2026-05-21T10:41:59.300754Z","steps":["trace[765007139] 'agreement among raft nodes before linearized reading' (duration: 123.564134ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:42:02.689628Z","caller":"traceutil/trace.go:171","msg":"trace[791708978] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"350.249168ms","start":"2026-05-21T10:42:02.339326Z","end":"2026-05-21T10:42:02.689575Z","steps":["trace[791708978] 'read index received' (duration: 299.621988ms)","trace[791708978] 'applied index is now lower than readState.Index' (duration: 50.62576ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:42:02.689669Z","caller":"traceutil/trace.go:171","msg":"trace[1989906245] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"380.48456ms","start":"2026-05-21T10:42:02.309133Z","end":"2026-05-21T10:42:02.689617Z","steps":["trace[1989906245] 'process raft request' (duration: 329.800467ms)","trace[1989906245] 'compare' (duration: 50.120687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:42:02.689916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.237747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-txp26\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T10:42:02.689989Z","caller":"traceutil/trace.go:171","msg":"trace[1173102127] range","detail":"{range_begin:/registry/pods/kube-system/cilium-txp26; range_end:; response_count:1; response_revision:632; }","duration":"331.33445ms","start":"2026-05-21T10:42:02.358639Z","end":"2026-05-21T10:42:02.689973Z","steps":["trace[1173102127] 'agreement among raft nodes before linearized reading' (duration: 331.123025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:02.690018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.473394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T10:42:02.690024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:02.358626Z","time spent":"331.389783ms","remote":"127.0.0.1:48502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-txp26\" "} {"level":"info","ts":"2026-05-21T10:42:02.690113Z","caller":"traceutil/trace.go:171","msg":"trace[1667597546] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:632; }","duration":"350.773203ms","start":"2026-05-21T10:42:02.33932Z","end":"2026-05-21T10:42:02.690093Z","steps":["trace[1667597546] 'agreement among raft nodes before linearized reading' (duration: 350.382382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:02.690168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:02.339263Z","time spent":"350.888586ms","remote":"127.0.0.1:48338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-05-21T10:42:02.690435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:02.309089Z","time spent":"380.668515ms","remote":"127.0.0.1:48824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T10:43:30.07946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.924521ms","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-05-21T10:43:30.07943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.928676ms","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-05-21T10:43:30.079572Z","caller":"traceutil/trace.go:171","msg":"trace[1869210535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"248.113046ms","start":"2026-05-21T10:43:29.831437Z","end":"2026-05-21T10:43:30.07955Z","steps":["trace[1869210535] 'range keys from in-memory index tree' (duration: 247.687962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:43:30.079559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.372476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-feca2ce7-dd55-465b-a538-0051201e79d4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-21T10:43:30.079597Z","caller":"traceutil/trace.go:171","msg":"trace[1713331203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"244.144753ms","start":"2026-05-21T10:43:29.835422Z","end":"2026-05-21T10:43:30.079567Z","steps":["trace[1713331203] 'range keys from in-memory index tree' (duration: 243.713031ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:43:30.079672Z","caller":"traceutil/trace.go:171","msg":"trace[1141121373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-feca2ce7-dd55-465b-a538-0051201e79d4; range_end:; response_count:1; response_revision:1850; }","duration":"246.550471ms","start":"2026-05-21T10:43:29.833092Z","end":"2026-05-21T10:43:30.079643Z","steps":["trace[1141121373] 'range keys from in-memory index tree' (duration: 246.135428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:44:14.393904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.868295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:44:14.394043Z","caller":"traceutil/trace.go:171","msg":"trace[976345618] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2149; }","duration":"105.04754ms","start":"2026-05-21T10:44:14.288949Z","end":"2026-05-21T10:44:14.393997Z","steps":["trace[976345618] 'count revisions from in-memory index tree' (duration: 104.774352ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:44:20.088096Z","caller":"traceutil/trace.go:171","msg":"trace[301046491] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"118.19568ms","start":"2026-05-21T10:44:19.969861Z","end":"2026-05-21T10:44:20.088057Z","steps":["trace[301046491] 'process raft request' (duration: 118.063546ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:44:26.074254Z","caller":"traceutil/trace.go:171","msg":"trace[1553198863] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2297; }","duration":"131.293828ms","start":"2026-05-21T10:44:25.94294Z","end":"2026-05-21T10:44:26.074234Z","steps":["trace[1553198863] 'read index received' (duration: 131.197255ms)","trace[1553198863] 'applied index is now lower than readState.Index' (duration: 95.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:44:26.074333Z","caller":"traceutil/trace.go:171","msg":"trace[607196701] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"194.776631ms","start":"2026-05-21T10:44:25.879523Z","end":"2026-05-21T10:44:26.0743Z","steps":["trace[607196701] 'process raft request' (duration: 194.588596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:44:26.074513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.550074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T10:44:26.074637Z","caller":"traceutil/trace.go:171","msg":"trace[1046557301] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2210; }","duration":"131.716659ms","start":"2026-05-21T10:44:25.942903Z","end":"2026-05-21T10:44:26.07462Z","steps":["trace[1046557301] 'agreement among raft nodes before linearized reading' (duration: 131.525154ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:44:26.152734Z","caller":"traceutil/trace.go:171","msg":"trace[1916629356] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"110.554886ms","start":"2026-05-21T10:44:26.042156Z","end":"2026-05-21T10:44:26.152711Z","steps":["trace[1916629356] 'process raft request' (duration: 110.419852ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:44:26.405727Z","caller":"traceutil/trace.go:171","msg":"trace[1110809080] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"297.709641ms","start":"2026-05-21T10:44:26.107974Z","end":"2026-05-21T10:44:26.405683Z","steps":["trace[1110809080] 'process raft request' (duration: 271.628949ms)","trace[1110809080] 'compare' (duration: 25.732763ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:49:43.158747Z","caller":"traceutil/trace.go:171","msg":"trace[1377403455] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"271.551497ms","start":"2026-05-21T10:49:42.887165Z","end":"2026-05-21T10:49:43.158716Z","steps":["trace[1377403455] 'process raft request' (duration: 271.376242ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:43.246103Z","caller":"traceutil/trace.go:171","msg":"trace[505058774] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"117.225922ms","start":"2026-05-21T10:49:43.128853Z","end":"2026-05-21T10:49:43.246079Z","steps":["trace[505058774] 'process raft request' (duration: 117.081678ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:43.246097Z","caller":"traceutil/trace.go:171","msg":"trace[1388409434] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"160.122786ms","start":"2026-05-21T10:49:43.085936Z","end":"2026-05-21T10:49:43.246059Z","steps":["trace[1388409434] 'process raft request' (duration: 159.844168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:49:45.011177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.801507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:49:45.011335Z","caller":"traceutil/trace.go:171","msg":"trace[1430218042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5061; }","duration":"187.041054ms","start":"2026-05-21T10:49:44.824266Z","end":"2026-05-21T10:49:45.011307Z","steps":["trace[1430218042] 'range keys from in-memory index tree' (duration: 186.679174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:49:45.011341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.412613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T10:49:45.011478Z","caller":"traceutil/trace.go:171","msg":"trace[904417168] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5061; }","duration":"220.635048ms","start":"2026-05-21T10:49:44.790798Z","end":"2026-05-21T10:49:45.011433Z","steps":["trace[904417168] 'count revisions from in-memory index tree' (duration: 220.213497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:49:45.011973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.788475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T10:49:45.012185Z","caller":"traceutil/trace.go:171","msg":"trace[1466689378] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"152.568016ms","start":"2026-05-21T10:49:44.859509Z","end":"2026-05-21T10:49:45.012077Z","steps":["trace[1466689378] 'process raft request' (duration: 24.136922ms)","trace[1466689378] 'compare' (duration: 127.60777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:49:54.02457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.683109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T10:49:54.025063Z","caller":"traceutil/trace.go:171","msg":"trace[706203972] linearizableReadLoop","detail":"{readStateIndex:5437; appliedIndex:5434; }","duration":"152.712769ms","start":"2026-05-21T10:49:53.872332Z","end":"2026-05-21T10:49:54.025045Z","steps":["trace[706203972] 'read index received' (duration: 14.152133ms)","trace[706203972] 'applied index is now lower than readState.Index' (duration: 138.559076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:49:54.025226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.888833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-21T10:49:54.025171Z","caller":"traceutil/trace.go:171","msg":"trace[242452198] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"152.811632ms","start":"2026-05-21T10:49:53.872303Z","end":"2026-05-21T10:49:54.025114Z","steps":["trace[242452198] 'process raft request' (duration: 152.644777ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:54.025274Z","caller":"traceutil/trace.go:171","msg":"trace[1784746389] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5238; }","duration":"152.967646ms","start":"2026-05-21T10:49:53.872291Z","end":"2026-05-21T10:49:54.025259Z","steps":["trace[1784746389] 'agreement among raft nodes before linearized reading' (duration: 152.806161ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:54.025239Z","caller":"traceutil/trace.go:171","msg":"trace[1456309422] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"155.003523ms","start":"2026-05-21T10:49:53.870167Z","end":"2026-05-21T10:49:54.02517Z","steps":["trace[1456309422] 'process raft request' (duration: 52.634635ms)","trace[1456309422] 'compare' (duration: 101.365821ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:49:54.192409Z","caller":"traceutil/trace.go:171","msg":"trace[1869702559] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"125.617476ms","start":"2026-05-21T10:49:54.066751Z","end":"2026-05-21T10:49:54.192369Z","steps":["trace[1869702559] 'process raft request' (duration: 100.115887ms)","trace[1869702559] 'compare' (duration: 25.254882ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:49:54.194211Z","caller":"traceutil/trace.go:171","msg":"trace[497795372] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"127.057415ms","start":"2026-05-21T10:49:54.067128Z","end":"2026-05-21T10:49:54.194185Z","steps":["trace[497795372] 'process raft request' (duration: 126.86709ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:54.194501Z","caller":"traceutil/trace.go:171","msg":"trace[1652465960] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"127.274742ms","start":"2026-05-21T10:49:54.067199Z","end":"2026-05-21T10:49:54.194473Z","steps":["trace[1652465960] 'process raft request' (duration: 126.921531ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:54.194675Z","caller":"traceutil/trace.go:171","msg":"trace[722442852] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"127.322534ms","start":"2026-05-21T10:49:54.067332Z","end":"2026-05-21T10:49:54.194654Z","steps":["trace[722442852] 'process raft request' (duration: 127.079346ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:49:54.194906Z","caller":"traceutil/trace.go:171","msg":"trace[1448518628] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"107.619045ms","start":"2026-05-21T10:49:54.087238Z","end":"2026-05-21T10:49:54.194857Z","steps":["trace[1448518628] 'process raft request' (duration: 107.306357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:50:02.698114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.426214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b335bf1-40c9-4a0e-acca-761a91c1716b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-21T10:50:02.698218Z","caller":"traceutil/trace.go:171","msg":"trace[1922291975] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b335bf1-40c9-4a0e-acca-761a91c1716b; range_end:; response_count:1; response_revision:5405; }","duration":"142.579708ms","start":"2026-05-21T10:50:02.555617Z","end":"2026-05-21T10:50:02.698197Z","steps":["trace[1922291975] 'range keys from in-memory index tree' (duration: 142.187758ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:50:15.859539Z","caller":"traceutil/trace.go:171","msg":"trace[882664257] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"137.62803ms","start":"2026-05-21T10:50:15.721886Z","end":"2026-05-21T10:50:15.859514Z","steps":["trace[882664257] 'read index received' (duration: 137.440455ms)","trace[882664257] 'applied index is now lower than readState.Index' (duration: 186.815µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:50:15.859586Z","caller":"traceutil/trace.go:171","msg":"trace[1965939792] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"140.679024ms","start":"2026-05-21T10:50:15.718885Z","end":"2026-05-21T10:50:15.859564Z","steps":["trace[1965939792] 'process raft request' (duration: 140.5001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:50:15.859799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.782642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T10:50:15.859834Z","caller":"traceutil/trace.go:171","msg":"trace[1500006128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5596; }","duration":"110.892016ms","start":"2026-05-21T10:50:15.748932Z","end":"2026-05-21T10:50:15.859824Z","steps":["trace[1500006128] 'agreement among raft nodes before linearized reading' (duration: 110.733671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:50:15.860204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.292208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81896"} {"level":"info","ts":"2026-05-21T10:50:15.860287Z","caller":"traceutil/trace.go:171","msg":"trace[896491293] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5596; }","duration":"138.410671ms","start":"2026-05-21T10:50:15.721858Z","end":"2026-05-21T10:50:15.860269Z","steps":["trace[896491293] 'agreement among raft nodes before linearized reading' (duration: 137.803514ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:51:19.360241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-05-21T10:51:19.43818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"76.317214ms","hash":474969360,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T10:51:19.438247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":474969360,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-05-21T10:56:03.610569Z","caller":"traceutil/trace.go:171","msg":"trace[1138208372] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"128.360957ms","start":"2026-05-21T10:56:03.482171Z","end":"2026-05-21T10:56:03.610531Z","steps":["trace[1138208372] 'process raft request' (duration: 128.091558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:03.790353Z","caller":"traceutil/trace.go:171","msg":"trace[1127176763] linearizableReadLoop","detail":"{readStateIndex:8532; appliedIndex:8531; }","duration":"135.711532ms","start":"2026-05-21T10:56:03.654618Z","end":"2026-05-21T10:56:03.79033Z","steps":["trace[1127176763] 'read index received' (duration: 96.229105ms)","trace[1127176763] 'applied index is now lower than readState.Index' (duration: 39.481447ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:56:03.790493Z","caller":"traceutil/trace.go:171","msg":"trace[335383713] transaction","detail":"{read_only:false; response_revision:8242; number_of_response:1; }","duration":"175.24966ms","start":"2026-05-21T10:56:03.615231Z","end":"2026-05-21T10:56:03.79048Z","steps":["trace[335383713] 'process raft request' (duration: 135.67338ms)","trace[335383713] 'compare' (duration: 39.130648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:56:03.790847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.205375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:56:03.79097Z","caller":"traceutil/trace.go:171","msg":"trace[2010110711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8242; }","duration":"136.353849ms","start":"2026-05-21T10:56:03.654575Z","end":"2026-05-21T10:56:03.790929Z","steps":["trace[2010110711] 'agreement among raft nodes before linearized reading' (duration: 136.166124ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:15.297198Z","caller":"traceutil/trace.go:171","msg":"trace[1221700062] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"176.949547ms","start":"2026-05-21T10:56:15.120187Z","end":"2026-05-21T10:56:15.297137Z","steps":["trace[1221700062] 'process raft request' (duration: 176.70369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:56:16.17049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.226842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:56:16.170603Z","caller":"traceutil/trace.go:171","msg":"trace[973722369] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8306; }","duration":"142.420268ms","start":"2026-05-21T10:56:16.028158Z","end":"2026-05-21T10:56:16.170579Z","steps":["trace[973722369] 'count revisions from in-memory index tree' (duration: 142.083288ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:19.366518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-05-21T10:56:19.459812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"91.319077ms","hash":3121717531,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":23330816,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-21T10:56:19.45989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3121717531,"revision":6134,"compact-revision":3020} {"level":"info","ts":"2026-05-21T10:56:45.686573Z","caller":"traceutil/trace.go:171","msg":"trace[1801630368] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"714.536364ms","start":"2026-05-21T10:56:44.971996Z","end":"2026-05-21T10:56:45.686533Z","steps":["trace[1801630368] 'process raft request' (duration: 714.36578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:56:45.686781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:56:44.971955Z","time spent":"714.725029ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T10:56:45.687783Z","caller":"traceutil/trace.go:171","msg":"trace[2039033904] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8819; }","duration":"285.104892ms","start":"2026-05-21T10:56:45.402651Z","end":"2026-05-21T10:56:45.687756Z","steps":["trace[2039033904] 'read index received' (duration: 283.774854ms)","trace[2039033904] 'applied index is now lower than readState.Index' (duration: 1.328758ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:56:45.68788Z","caller":"traceutil/trace.go:171","msg":"trace[1912179208] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"618.341262ms","start":"2026-05-21T10:56:45.069476Z","end":"2026-05-21T10:56:45.687817Z","steps":["trace[1912179208] 'process raft request' (duration: 618.079914ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:45.68794Z","caller":"traceutil/trace.go:171","msg":"trace[725612256] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"241.293935ms","start":"2026-05-21T10:56:45.446632Z","end":"2026-05-21T10:56:45.687926Z","steps":["trace[725612256] 'process raft request' (duration: 241.062578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:56:45.688142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:56:45.069449Z","time spent":"618.523057ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T10:56:45.688398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.711309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-21T10:56:45.688564Z","caller":"traceutil/trace.go:171","msg":"trace[2076668895] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8520; }","duration":"285.954335ms","start":"2026-05-21T10:56:45.402585Z","end":"2026-05-21T10:56:45.688539Z","steps":["trace[2076668895] 'agreement among raft nodes before linearized reading' (duration: 285.472622ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:57.660847Z","caller":"traceutil/trace.go:171","msg":"trace[2002775240] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8626; }","duration":"115.569342ms","start":"2026-05-21T10:56:57.54524Z","end":"2026-05-21T10:56:57.660809Z","steps":["trace[2002775240] 'process raft request' (duration: 99.991829ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:57.666565Z","caller":"traceutil/trace.go:171","msg":"trace[926666099] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"119.065229ms","start":"2026-05-21T10:56:57.547466Z","end":"2026-05-21T10:56:57.666531Z","steps":["trace[926666099] 'process raft request' (duration: 118.774671ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:56:58.411256Z","caller":"traceutil/trace.go:171","msg":"trace[979315939] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"114.597164ms","start":"2026-05-21T10:56:58.296628Z","end":"2026-05-21T10:56:58.411225Z","steps":["trace[979315939] 'process raft request' (duration: 114.45498ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:57:21.567978Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002406.wal"} {"level":"info","ts":"2026-05-21T10:58:52.573998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T10:58:52.579959Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-21T10:58:52.580052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-21T11:01:19.372916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8332} {"level":"info","ts":"2026-05-21T11:01:19.449122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8332,"took":"74.084304ms","hash":3485168672,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":25513984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-21T11:01:19.449187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485168672,"revision":8332,"compact-revision":6134} {"level":"info","ts":"2026-05-21T11:06:19.379302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10822} {"level":"info","ts":"2026-05-21T11:06:19.455437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10822,"took":"72.915053ms","hash":2349085558,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":28123136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-21T11:06:19.455497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2349085558,"revision":10822,"compact-revision":8332} {"level":"info","ts":"2026-05-21T11:07:52.509463Z","caller":"traceutil/trace.go:171","msg":"trace[2142690134] transaction","detail":"{read_only:false; response_revision:13670; number_of_response:1; }","duration":"115.300449ms","start":"2026-05-21T11:07:52.394139Z","end":"2026-05-21T11:07:52.509439Z","steps":["trace[2142690134] 'process raft request' (duration: 114.573639ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:08:39.755608Z","caller":"traceutil/trace.go:171","msg":"trace[1831169665] transaction","detail":"{read_only:false; response_revision:14052; number_of_response:1; }","duration":"111.669158ms","start":"2026-05-21T11:08:39.643899Z","end":"2026-05-21T11:08:39.755568Z","steps":["trace[1831169665] 'process raft request' (duration: 111.505504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:08:40.073707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.110837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T11:08:40.073901Z","caller":"traceutil/trace.go:171","msg":"trace[1064796266] linearizableReadLoop","detail":"{readStateIndex:14532; appliedIndex:14531; }","duration":"250.022176ms","start":"2026-05-21T11:08:39.823858Z","end":"2026-05-21T11:08:40.073881Z","steps":["trace[1064796266] 'read index received' (duration: 105.621611ms)","trace[1064796266] 'applied index is now lower than readState.Index' (duration: 144.398685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:08:40.074062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.198411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T11:08:40.074123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.412516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T11:08:40.074166Z","caller":"traceutil/trace.go:171","msg":"trace[550300734] transaction","detail":"{read_only:false; response_revision:14053; number_of_response:1; }","duration":"312.798257ms","start":"2026-05-21T11:08:39.761141Z","end":"2026-05-21T11:08:40.073939Z","steps":["trace[550300734] 'process raft request' (duration: 168.3569ms)","trace[550300734] 'compare' (duration: 143.935933ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:08:40.074283Z","caller":"traceutil/trace.go:171","msg":"trace[1234461371] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14053; }","duration":"131.660012ms","start":"2026-05-21T11:08:39.942602Z","end":"2026-05-21T11:08:40.074263Z","steps":["trace[1234461371] 'agreement among raft nodes before linearized reading' (duration: 131.457737ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:08:40.074156Z","caller":"traceutil/trace.go:171","msg":"trace[1690679576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14053; }","duration":"250.334405ms","start":"2026-05-21T11:08:39.823797Z","end":"2026-05-21T11:08:40.074132Z","steps":["trace[1690679576] 'agreement among raft nodes before linearized reading' (duration: 250.177031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:08:40.074552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:08:39.761076Z","time spent":"313.308201ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:10:20.975653Z","caller":"traceutil/trace.go:171","msg":"trace[453758656] transaction","detail":"{read_only:false; response_revision:15147; number_of_response:1; }","duration":"103.700022ms","start":"2026-05-21T11:10:20.871916Z","end":"2026-05-21T11:10:20.975616Z","steps":["trace[453758656] 'process raft request' (duration: 103.494097ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:10:21.715056Z","caller":"traceutil/trace.go:171","msg":"trace[2067437547] linearizableReadLoop","detail":"{readStateIndex:15663; appliedIndex:15662; }","duration":"100.221227ms","start":"2026-05-21T11:10:21.614811Z","end":"2026-05-21T11:10:21.715032Z","steps":["trace[2067437547] 'read index received' (duration: 100.036222ms)","trace[2067437547] 'applied index is now lower than readState.Index' (duration: 183.835µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:10:21.715332Z","caller":"traceutil/trace.go:171","msg":"trace[669337933] transaction","detail":"{read_only:false; response_revision:15148; number_of_response:1; }","duration":"124.557635ms","start":"2026-05-21T11:10:21.59076Z","end":"2026-05-21T11:10:21.715317Z","steps":["trace[669337933] 'process raft request' (duration: 124.140963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:10:21.715642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.808722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T11:10:21.71569Z","caller":"traceutil/trace.go:171","msg":"trace[760000468] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:15148; }","duration":"100.936676ms","start":"2026-05-21T11:10:21.614741Z","end":"2026-05-21T11:10:21.715677Z","steps":["trace[760000468] 'agreement among raft nodes before linearized reading' (duration: 100.837994ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:11:19.385907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13032} {"level":"info","ts":"2026-05-21T11:11:19.466591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13032,"took":"77.424272ms","hash":2040680777,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-21T11:11:19.466675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040680777,"revision":13032,"compact-revision":10822} {"level":"info","ts":"2026-05-21T11:15:57.934714Z","caller":"traceutil/trace.go:171","msg":"trace[1554069303] linearizableReadLoop","detail":"{readStateIndex:18331; appliedIndex:18330; }","duration":"112.044655ms","start":"2026-05-21T11:15:57.822638Z","end":"2026-05-21T11:15:57.934683Z","steps":["trace[1554069303] 'read index received' (duration: 111.904951ms)","trace[1554069303] 'applied index is now lower than readState.Index' (duration: 138.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:15:57.934765Z","caller":"traceutil/trace.go:171","msg":"trace[1967805382] transaction","detail":"{read_only:false; response_revision:17732; number_of_response:1; }","duration":"117.672119ms","start":"2026-05-21T11:15:57.817058Z","end":"2026-05-21T11:15:57.93473Z","steps":["trace[1967805382] 'process raft request' (duration: 117.411582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:15:57.934899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.23287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:15:57.934995Z","caller":"traceutil/trace.go:171","msg":"trace[1638088009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17732; }","duration":"112.346183ms","start":"2026-05-21T11:15:57.822631Z","end":"2026-05-21T11:15:57.934977Z","steps":["trace[1638088009] 'agreement among raft nodes before linearized reading' (duration: 112.173188ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.185649Z","caller":"traceutil/trace.go:171","msg":"trace[2012611571] transaction","detail":"{read_only:false; response_revision:17769; number_of_response:1; }","duration":"152.584811ms","start":"2026-05-21T11:16:04.032979Z","end":"2026-05-21T11:16:04.185563Z","steps":["trace[2012611571] 'process raft request' (duration: 152.366685ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.351622Z","caller":"traceutil/trace.go:171","msg":"trace[1741880314] transaction","detail":"{read_only:false; response_revision:17770; number_of_response:1; }","duration":"250.062525ms","start":"2026-05-21T11:16:04.101527Z","end":"2026-05-21T11:16:04.351589Z","steps":["trace[1741880314] 'process raft request' (duration: 249.770927ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.351688Z","caller":"traceutil/trace.go:171","msg":"trace[297481810] transaction","detail":"{read_only:false; response_revision:17771; number_of_response:1; }","duration":"237.968923ms","start":"2026-05-21T11:16:04.113694Z","end":"2026-05-21T11:16:04.351662Z","steps":["trace[297481810] 'process raft request' (duration: 237.782058ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.351776Z","caller":"traceutil/trace.go:171","msg":"trace[896282040] transaction","detail":"{read_only:false; response_revision:17772; number_of_response:1; }","duration":"237.385585ms","start":"2026-05-21T11:16:04.114343Z","end":"2026-05-21T11:16:04.351729Z","steps":["trace[896282040] 'process raft request' (duration: 237.17883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.937206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.473876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:16:04.937341Z","caller":"traceutil/trace.go:171","msg":"trace[458397018] transaction","detail":"{read_only:false; response_revision:17776; number_of_response:1; }","duration":"355.24636ms","start":"2026-05-21T11:16:04.582082Z","end":"2026-05-21T11:16:04.937329Z","steps":["trace[458397018] 'process raft request' (duration: 355.203209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.937391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.582042Z","time spent":"355.326642ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:16:04.937537Z","caller":"traceutil/trace.go:171","msg":"trace[828972229] linearizableReadLoop","detail":"{readStateIndex:18376; appliedIndex:18375; }","duration":"492.935811ms","start":"2026-05-21T11:16:04.444592Z","end":"2026-05-21T11:16:04.937527Z","steps":["trace[828972229] 'read index received' (duration: 230.195288ms)","trace[828972229] 'applied index is now lower than readState.Index' (duration: 262.739093ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:16:04.937492Z","caller":"traceutil/trace.go:171","msg":"trace[457809053] transaction","detail":"{read_only:false; response_revision:17775; number_of_response:1; }","duration":"496.011286ms","start":"2026-05-21T11:16:04.441445Z","end":"2026-05-21T11:16:04.937457Z","steps":["trace[457809053] 'process raft request' (duration: 233.218492ms)","trace[457809053] 'compare' (duration: 262.312231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:16:04.937764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.819234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hthnw\" ","response":"range_response_count:1 size:22126"} {"level":"warn","ts":"2026-05-21T11:16:04.937741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.441424Z","time spent":"496.199823ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:16:04.937791Z","caller":"traceutil/trace.go:171","msg":"trace[1484569946] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hthnw; range_end:; response_count:1; response_revision:17776; }","duration":"312.879484ms","start":"2026-05-21T11:16:04.624905Z","end":"2026-05-21T11:16:04.937785Z","steps":["trace[1484569946] 'agreement among raft nodes before linearized reading' (duration: 312.771121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.937778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.166245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T11:16:04.937813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.624885Z","time spent":"312.922326ms","remote":"127.0.0.1:48502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22148,"request content":"key:\"/registry/pods/openstack/nova-compute-default-hthnw\" "} {"level":"info","ts":"2026-05-21T11:16:04.937834Z","caller":"traceutil/trace.go:171","msg":"trace[1566945997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17776; }","duration":"438.265647ms","start":"2026-05-21T11:16:04.499553Z","end":"2026-05-21T11:16:04.937818Z","steps":["trace[1566945997] 'agreement among raft nodes before linearized reading' (duration: 438.060292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.937876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.499538Z","time spent":"438.3315ms","remote":"127.0.0.1:48598","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-05-21T11:16:04.937921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.310642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"warn","ts":"2026-05-21T11:16:04.938028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.520597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-21T11:16:04.938016Z","caller":"traceutil/trace.go:171","msg":"trace[1036337362] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17776; }","duration":"493.461927ms","start":"2026-05-21T11:16:04.444535Z","end":"2026-05-21T11:16:04.937997Z","steps":["trace[1036337362] 'agreement among raft nodes before linearized reading' (duration: 493.19908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.938055Z","caller":"traceutil/trace.go:171","msg":"trace[1538988227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17776; }","duration":"433.554718ms","start":"2026-05-21T11:16:04.504492Z","end":"2026-05-21T11:16:04.938047Z","steps":["trace[1538988227] 'agreement among raft nodes before linearized reading' (duration: 433.488736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.938013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.656003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-21T11:16:04.938065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.444513Z","time spent":"493.54312ms","remote":"127.0.0.1:47004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3321,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"warn","ts":"2026-05-21T11:16:04.938076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.504486Z","time spent":"433.585668ms","remote":"127.0.0.1:48598","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-05-21T11:16:04.938081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.633671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:16:04.938106Z","caller":"traceutil/trace.go:171","msg":"trace[1744688600] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:17776; }","duration":"416.674303ms","start":"2026-05-21T11:16:04.521426Z","end":"2026-05-21T11:16:04.9381Z","steps":["trace[1744688600] 'agreement among raft nodes before linearized reading' (duration: 416.627312ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:16:04.938098Z","caller":"traceutil/trace.go:171","msg":"trace[1925505216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17776; }","duration":"432.750355ms","start":"2026-05-21T11:16:04.50533Z","end":"2026-05-21T11:16:04.93808Z","steps":["trace[1925505216] 'agreement among raft nodes before linearized reading' (duration: 432.519009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:16:04.938174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.505325Z","time spent":"432.838398ms","remote":"127.0.0.1:48598","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-05-21T11:16:04.938125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:16:04.521419Z","time spent":"416.701664ms","remote":"127.0.0.1:48474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":14,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-05-21T11:16:19.395963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15512} {"level":"info","ts":"2026-05-21T11:16:19.502889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15512,"took":"103.079388ms","hash":3590624660,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":33034240,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-21T11:16:19.502958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3590624660,"revision":15512,"compact-revision":13032} {"level":"info","ts":"2026-05-21T11:19:19.629617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T11:19:19.634793Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-21T11:19:19.634895Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-21T11:21:19.402195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18061} {"level":"info","ts":"2026-05-21T11:21:19.525645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18061,"took":"117.606223ms","hash":567524628,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T11:21:19.525755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":567524628,"revision":18061,"compact-revision":15512} {"level":"info","ts":"2026-05-21T11:21:27.694362Z","caller":"traceutil/trace.go:171","msg":"trace[914134352] transaction","detail":"{read_only:false; response_revision:20241; number_of_response:1; }","duration":"133.399664ms","start":"2026-05-21T11:21:27.560938Z","end":"2026-05-21T11:21:27.694338Z","steps":["trace[914134352] 'process raft request' (duration: 133.218299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:27.802697Z","caller":"traceutil/trace.go:171","msg":"trace[332857745] linearizableReadLoop","detail":"{readStateIndex:20925; appliedIndex:20924; }","duration":"189.996344ms","start":"2026-05-21T11:21:27.612681Z","end":"2026-05-21T11:21:27.802677Z","steps":["trace[332857745] 'read index received' (duration: 81.713875ms)","trace[332857745] 'applied index is now lower than readState.Index' (duration: 108.281299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:21:27.802912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.21452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7697"} {"level":"info","ts":"2026-05-21T11:21:27.802955Z","caller":"traceutil/trace.go:171","msg":"trace[6597445] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20243; }","duration":"190.300593ms","start":"2026-05-21T11:21:27.612641Z","end":"2026-05-21T11:21:27.802942Z","steps":["trace[6597445] 'agreement among raft nodes before linearized reading' (duration: 190.120897ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:27.803149Z","caller":"traceutil/trace.go:171","msg":"trace[1366792082] transaction","detail":"{read_only:false; response_revision:20242; number_of_response:1; }","duration":"238.715132ms","start":"2026-05-21T11:21:27.564424Z","end":"2026-05-21T11:21:27.803139Z","steps":["trace[1366792082] 'process raft request' (duration: 233.971893ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:27.803259Z","caller":"traceutil/trace.go:171","msg":"trace[433146595] transaction","detail":"{read_only:false; response_revision:20243; number_of_response:1; }","duration":"184.272639ms","start":"2026-05-21T11:21:27.618956Z","end":"2026-05-21T11:21:27.803229Z","steps":["trace[433146595] 'process raft request' (duration: 183.674802ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:27.859502Z","caller":"traceutil/trace.go:171","msg":"trace[1163760829] transaction","detail":"{read_only:false; response_revision:20244; number_of_response:1; }","duration":"108.237708ms","start":"2026-05-21T11:21:27.751223Z","end":"2026-05-21T11:21:27.859461Z","steps":["trace[1163760829] 'process raft request' (duration: 108.016792ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:28.102685Z","caller":"traceutil/trace.go:171","msg":"trace[2142723554] transaction","detail":"{read_only:false; response_revision:20245; number_of_response:1; }","duration":"127.276755ms","start":"2026-05-21T11:21:27.975377Z","end":"2026-05-21T11:21:28.102654Z","steps":["trace[2142723554] 'process raft request' (duration: 126.684569ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:28.265266Z","caller":"traceutil/trace.go:171","msg":"trace[1883996707] transaction","detail":"{read_only:false; response_revision:20246; number_of_response:1; }","duration":"157.927982ms","start":"2026-05-21T11:21:28.107282Z","end":"2026-05-21T11:21:28.26521Z","steps":["trace[1883996707] 'process raft request' (duration: 107.982551ms)","trace[1883996707] 'compare' (duration: 49.632201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:21:28.718258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.723337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:21:28.718383Z","caller":"traceutil/trace.go:171","msg":"trace[1035956661] linearizableReadLoop","detail":"{readStateIndex:20932; appliedIndex:20931; }","duration":"128.473649ms","start":"2026-05-21T11:21:28.589891Z","end":"2026-05-21T11:21:28.718365Z","steps":["trace[1035956661] 'read index received' (duration: 25.374231ms)","trace[1035956661] 'applied index is now lower than readState.Index' (duration: 103.097888ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:21:28.718494Z","caller":"traceutil/trace.go:171","msg":"trace[234649780] transaction","detail":"{read_only:false; response_revision:20248; number_of_response:1; }","duration":"341.375968ms","start":"2026-05-21T11:21:28.377079Z","end":"2026-05-21T11:21:28.718455Z","steps":["trace[234649780] 'process raft request' (duration: 238.359783ms)","trace[234649780] 'compare' (duration: 102.576213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:21:28.718579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.691815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-21T11:21:28.718604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:21:28.377046Z","time spent":"341.506231ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:21:28.718619Z","caller":"traceutil/trace.go:171","msg":"trace[1692631111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20248; }","duration":"128.774177ms","start":"2026-05-21T11:21:28.589831Z","end":"2026-05-21T11:21:28.718605Z","steps":["trace[1692631111] 'agreement among raft nodes before linearized reading' (duration: 128.626643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:21:28.718695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.020548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:21:28.718902Z","caller":"traceutil/trace.go:171","msg":"trace[1079161525] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:20248; }","duration":"113.169312ms","start":"2026-05-21T11:21:28.60558Z","end":"2026-05-21T11:21:28.718749Z","steps":["trace[1079161525] 'agreement among raft nodes before linearized reading' (duration: 112.954666ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:28.927134Z","caller":"traceutil/trace.go:171","msg":"trace[727566631] linearizableReadLoop","detail":"{readStateIndex:20935; appliedIndex:20933; }","duration":"104.340742ms","start":"2026-05-21T11:21:28.822765Z","end":"2026-05-21T11:21:28.927106Z","steps":["trace[727566631] 'read index received' (duration: 104.132046ms)","trace[727566631] 'applied index is now lower than readState.Index' (duration: 207.116µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:21:28.927199Z","caller":"traceutil/trace.go:171","msg":"trace[1817375581] transaction","detail":"{read_only:false; response_revision:20250; number_of_response:1; }","duration":"134.618396ms","start":"2026-05-21T11:21:28.792528Z","end":"2026-05-21T11:21:28.927147Z","steps":["trace[1817375581] 'process raft request' (duration: 134.329528ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:21:28.927226Z","caller":"traceutil/trace.go:171","msg":"trace[1545955650] transaction","detail":"{read_only:false; response_revision:20251; number_of_response:1; }","duration":"134.621766ms","start":"2026-05-21T11:21:28.792553Z","end":"2026-05-21T11:21:28.927174Z","steps":["trace[1545955650] 'process raft request' (duration: 134.504493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:21:28.92731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.514146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:21:28.927352Z","caller":"traceutil/trace.go:171","msg":"trace[136932967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20251; }","duration":"104.601038ms","start":"2026-05-21T11:21:28.822738Z","end":"2026-05-21T11:21:28.927339Z","steps":["trace[136932967] 'agreement among raft nodes before linearized reading' (duration: 104.479625ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:25:19.553431Z","caller":"traceutil/trace.go:171","msg":"trace[175249154] linearizableReadLoop","detail":"{readStateIndex:23404; appliedIndex:23403; }","duration":"235.928345ms","start":"2026-05-21T11:25:19.317472Z","end":"2026-05-21T11:25:19.553401Z","steps":["trace[175249154] 'read index received' (duration: 235.653467ms)","trace[175249154] 'applied index is now lower than readState.Index' (duration: 273.318µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:25:19.553728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.232223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-21T11:25:19.553826Z","caller":"traceutil/trace.go:171","msg":"trace[2136183444] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:22650; }","duration":"236.396048ms","start":"2026-05-21T11:25:19.31741Z","end":"2026-05-21T11:25:19.553806Z","steps":["trace[2136183444] 'agreement among raft nodes before linearized reading' (duration: 236.150602ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:25:19.554086Z","caller":"traceutil/trace.go:171","msg":"trace[1387299906] transaction","detail":"{read_only:false; response_revision:22650; number_of_response:1; }","duration":"237.912709ms","start":"2026-05-21T11:25:19.315601Z","end":"2026-05-21T11:25:19.553514Z","steps":["trace[1387299906] 'process raft request' (duration: 237.535918ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:25:19.554474Z","caller":"traceutil/trace.go:171","msg":"trace[1693832913] transaction","detail":"{read_only:false; response_revision:22651; number_of_response:1; }","duration":"155.635821ms","start":"2026-05-21T11:25:19.398787Z","end":"2026-05-21T11:25:19.554423Z","steps":["trace[1693832913] 'process raft request' (duration: 155.470737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:25:19.554772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.513323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-21T11:25:19.554842Z","caller":"traceutil/trace.go:171","msg":"trace[1508452877] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22652; }","duration":"120.644176ms","start":"2026-05-21T11:25:19.43418Z","end":"2026-05-21T11:25:19.554825Z","steps":["trace[1508452877] 'agreement among raft nodes before linearized reading' (duration: 120.41349ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:26:19.409425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20198} {"level":"info","ts":"2026-05-21T11:26:19.517294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20198,"took":"103.841783ms","hash":4248800248,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":40902656,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-21T11:26:19.517368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248800248,"revision":20198,"compact-revision":18061} {"level":"info","ts":"2026-05-21T11:28:50.703586Z","caller":"traceutil/trace.go:171","msg":"trace[427119534] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"119.635402ms","start":"2026-05-21T11:28:50.583919Z","end":"2026-05-21T11:28:50.703554Z","steps":["trace[427119534] 'process raft request' (duration: 119.254912ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:56.116913Z","caller":"traceutil/trace.go:171","msg":"trace[963797321] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"102.320872ms","start":"2026-05-21T11:28:56.014543Z","end":"2026-05-21T11:28:56.116864Z","steps":["trace[963797321] 'process raft request' (duration: 102.036674ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:56.610935Z","caller":"traceutil/trace.go:171","msg":"trace[177084674] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"310.164692ms","start":"2026-05-21T11:28:56.300743Z","end":"2026-05-21T11:28:56.610908Z","steps":["trace[177084674] 'process raft request' (duration: 285.010678ms)","trace[177084674] 'compare' (duration: 25.014811ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:56.611064Z","caller":"traceutil/trace.go:171","msg":"trace[1132935898] linearizableReadLoop","detail":"{readStateIndex:25620; appliedIndex:25617; }","duration":"290.759765ms","start":"2026-05-21T11:28:56.320287Z","end":"2026-05-21T11:28:56.611047Z","steps":["trace[1132935898] 'read index received' (duration: 17.163356ms)","trace[1132935898] 'applied index is now lower than readState.Index' (duration: 273.595279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:56.611251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.95014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-21T11:28:56.611299Z","caller":"traceutil/trace.go:171","msg":"trace[666539315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24810; }","duration":"291.035233ms","start":"2026-05-21T11:28:56.320252Z","end":"2026-05-21T11:28:56.611287Z","steps":["trace[666539315] 'agreement among raft nodes before linearized reading' (duration: 290.858078ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:56.611533Z","caller":"traceutil/trace.go:171","msg":"trace[661491115] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"308.889077ms","start":"2026-05-21T11:28:56.302633Z","end":"2026-05-21T11:28:56.611522Z","steps":["trace[661491115] 'process raft request' (duration: 308.240759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:56.611618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:56.302614Z","time spent":"308.951509ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:28:56.612203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:56.300725Z","time spent":"310.275856ms","remote":"127.0.0.1:48598","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-05-21T11:28:56.720528Z","caller":"traceutil/trace.go:171","msg":"trace[1943909188] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"105.232972ms","start":"2026-05-21T11:28:56.61527Z","end":"2026-05-21T11:28:56.720503Z","steps":["trace[1943909188] 'process raft request' (duration: 101.608323ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:57.067158Z","caller":"traceutil/trace.go:171","msg":"trace[98454858] linearizableReadLoop","detail":"{readStateIndex:25622; appliedIndex:25621; }","duration":"286.733416ms","start":"2026-05-21T11:28:56.780392Z","end":"2026-05-21T11:28:57.067126Z","steps":["trace[98454858] 'read index received' (duration: 272.929601ms)","trace[98454858] 'applied index is now lower than readState.Index' (duration: 13.802105ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:57.067328Z","caller":"traceutil/trace.go:171","msg":"trace[703119042] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"333.307061ms","start":"2026-05-21T11:28:56.733993Z","end":"2026-05-21T11:28:57.0673Z","steps":["trace[703119042] 'process raft request' (duration: 319.396643ms)","trace[703119042] 'compare' (duration: 13.523237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:57.067379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.967132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:28:57.067433Z","caller":"traceutil/trace.go:171","msg":"trace[1507398516] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24812; }","duration":"287.038634ms","start":"2026-05-21T11:28:56.780378Z","end":"2026-05-21T11:28:57.067416Z","steps":["trace[1507398516] 'agreement among raft nodes before linearized reading' (duration: 286.924831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:57.067477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:56.733981Z","time spent":"333.422465ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:28:57.067637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.26027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:28:57.067698Z","caller":"traceutil/trace.go:171","msg":"trace[926505133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24812; }","duration":"244.358653ms","start":"2026-05-21T11:28:56.823324Z","end":"2026-05-21T11:28:57.067682Z","steps":["trace[926505133] 'agreement among raft nodes before linearized reading' (duration: 244.2628ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:57.497165Z","caller":"traceutil/trace.go:171","msg":"trace[292262819] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"130.156768ms","start":"2026-05-21T11:28:57.366983Z","end":"2026-05-21T11:28:57.497139Z","steps":["trace[292262819] 'process raft request' (duration: 130.084646ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:57.497178Z","caller":"traceutil/trace.go:171","msg":"trace[1723324269] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"188.988747ms","start":"2026-05-21T11:28:57.308154Z","end":"2026-05-21T11:28:57.497143Z","steps":["trace[1723324269] 'process raft request' (duration: 152.328721ms)","trace[1723324269] 'compare' (duration: 36.43131ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:57.57148Z","caller":"traceutil/trace.go:171","msg":"trace[1154907536] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"123.341773ms","start":"2026-05-21T11:28:57.448117Z","end":"2026-05-21T11:28:57.571458Z","steps":["trace[1154907536] 'process raft request' (duration: 123.197809ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:57.571644Z","caller":"traceutil/trace.go:171","msg":"trace[2089111858] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"120.463615ms","start":"2026-05-21T11:28:57.451154Z","end":"2026-05-21T11:28:57.571617Z","steps":["trace[2089111858] 'process raft request' (duration: 120.27696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:57.864974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.801077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:28:57.865274Z","caller":"traceutil/trace.go:171","msg":"trace[858611807] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"331.993276ms","start":"2026-05-21T11:28:57.53326Z","end":"2026-05-21T11:28:57.865253Z","steps":["trace[858611807] 'process raft request' (duration: 172.814918ms)","trace[858611807] 'compare' (duration: 158.558021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:57.86542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:57.533233Z","time spent":"332.080058ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:28:58.719348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.560371ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T11:28:58.719954Z","caller":"traceutil/trace.go:171","msg":"trace[1527278093] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"597.232768ms","start":"2026-05-21T11:28:58.1227Z","end":"2026-05-21T11:28:58.719932Z","steps":["trace[1527278093] 'process raft request' (duration: 596.753054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:58.720069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:58.122677Z","time spent":"597.323869ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:28:58.733867Z","caller":"traceutil/trace.go:171","msg":"trace[1889716840] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"388.719428ms","start":"2026-05-21T11:28:58.34513Z","end":"2026-05-21T11:28:58.73385Z","steps":["trace[1889716840] 'process raft request' (duration: 388.589794ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:58.73392Z","caller":"traceutil/trace.go:171","msg":"trace[105737716] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"261.851089ms","start":"2026-05-21T11:28:58.472047Z","end":"2026-05-21T11:28:58.733898Z","steps":["trace[105737716] 'process raft request' (duration: 261.721536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:58.733999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:58.345108Z","time spent":"388.822381ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:28:58.734083Z","caller":"traceutil/trace.go:171","msg":"trace[231160610] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"115.788069ms","start":"2026-05-21T11:28:58.618286Z","end":"2026-05-21T11:28:58.734074Z","steps":["trace[231160610] 'process raft request' (duration: 115.518262ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:58.73398Z","caller":"traceutil/trace.go:171","msg":"trace[473257323] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"404.478427ms","start":"2026-05-21T11:28:58.329399Z","end":"2026-05-21T11:28:58.733877Z","steps":["trace[473257323] 'process raft request' (duration: 402.746329ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:58.734342Z","caller":"traceutil/trace.go:171","msg":"trace[1050436180] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"113.796334ms","start":"2026-05-21T11:28:58.62032Z","end":"2026-05-21T11:28:58.734117Z","steps":["trace[1050436180] 'process raft request' (duration: 113.522747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:58.734483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:58.329374Z","time spent":"404.96028ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:28:59.046563Z","caller":"traceutil/trace.go:171","msg":"trace[1389802393] linearizableReadLoop","detail":"{readStateIndex:25637; appliedIndex:25636; }","duration":"293.113188ms","start":"2026-05-21T11:28:58.753425Z","end":"2026-05-21T11:28:59.046538Z","steps":["trace[1389802393] 'read index received' (duration: 213.612777ms)","trace[1389802393] 'applied index is now lower than readState.Index' (duration: 79.499531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:59.046735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.853796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:28:59.046776Z","caller":"traceutil/trace.go:171","msg":"trace[1094810490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24826; }","duration":"223.932678ms","start":"2026-05-21T11:28:58.82283Z","end":"2026-05-21T11:28:59.046762Z","steps":["trace[1094810490] 'agreement among raft nodes before linearized reading' (duration: 223.857096ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:59.046891Z","caller":"traceutil/trace.go:171","msg":"trace[155847040] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"308.6327ms","start":"2026-05-21T11:28:58.738223Z","end":"2026-05-21T11:28:59.046856Z","steps":["trace[155847040] 'process raft request' (duration: 228.78339ms)","trace[155847040] 'compare' (duration: 79.298796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:59.047064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:28:58.738195Z","time spent":"308.771614ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:28:59.046905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.446157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-05-21T11:28:59.047224Z","caller":"traceutil/trace.go:171","msg":"trace[379440449] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24826; }","duration":"293.837598ms","start":"2026-05-21T11:28:58.753363Z","end":"2026-05-21T11:28:59.047201Z","steps":["trace[379440449] 'agreement among raft nodes before linearized reading' (duration: 293.315614ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:59.316882Z","caller":"traceutil/trace.go:171","msg":"trace[374459969] linearizableReadLoop","detail":"{readStateIndex:25638; appliedIndex:25637; }","duration":"219.603201ms","start":"2026-05-21T11:28:59.097248Z","end":"2026-05-21T11:28:59.316852Z","steps":["trace[374459969] 'read index received' (duration: 188.602979ms)","trace[374459969] 'applied index is now lower than readState.Index' (duration: 30.999192ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:59.317064Z","caller":"traceutil/trace.go:171","msg":"trace[1551627725] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"244.204669ms","start":"2026-05-21T11:28:59.072839Z","end":"2026-05-21T11:28:59.317043Z","steps":["trace[1551627725] 'process raft request' (duration: 213.081852ms)","trace[1551627725] 'compare' (duration: 30.789638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:28:59.317255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.330872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-21T11:28:59.317325Z","caller":"traceutil/trace.go:171","msg":"trace[939103347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24827; }","duration":"219.427336ms","start":"2026-05-21T11:28:59.097882Z","end":"2026-05-21T11:28:59.317309Z","steps":["trace[939103347] 'agreement among raft nodes before linearized reading' (duration: 219.237621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:59.317499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.228968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:28:59.317584Z","caller":"traceutil/trace.go:171","msg":"trace[675731248] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24827; }","duration":"220.360352ms","start":"2026-05-21T11:28:59.097202Z","end":"2026-05-21T11:28:59.317563Z","steps":["trace[675731248] 'agreement among raft nodes before linearized reading' (duration: 220.210058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:28:59.592701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.53484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:28:59.593181Z","caller":"traceutil/trace.go:171","msg":"trace[1988174704] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"184.024842ms","start":"2026-05-21T11:28:59.409136Z","end":"2026-05-21T11:28:59.593161Z","steps":["trace[1988174704] 'process raft request' (duration: 56.946208ms)","trace[1988174704] 'compare' (duration: 126.15431ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:59.721691Z","caller":"traceutil/trace.go:171","msg":"trace[767088131] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"144.752064ms","start":"2026-05-21T11:28:59.576914Z","end":"2026-05-21T11:28:59.721666Z","steps":["trace[767088131] 'process raft request' (duration: 144.653161ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:28:59.7221Z","caller":"traceutil/trace.go:171","msg":"trace[883599412] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"145.832674ms","start":"2026-05-21T11:28:59.576247Z","end":"2026-05-21T11:28:59.722079Z","steps":["trace[883599412] 'process raft request' (duration: 125.7928ms)","trace[883599412] 'compare' (duration: 19.381576ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:28:59.72217Z","caller":"traceutil/trace.go:171","msg":"trace[910108310] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"115.258624ms","start":"2026-05-21T11:28:59.606874Z","end":"2026-05-21T11:28:59.722132Z","steps":["trace[910108310] 'process raft request' (duration: 114.75275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:14.471835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.729698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-21T11:29:14.471923Z","caller":"traceutil/trace.go:171","msg":"trace[1457093278] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833; range_end:; response_count:1; response_revision:25000; }","duration":"164.864171ms","start":"2026-05-21T11:29:14.307037Z","end":"2026-05-21T11:29:14.471902Z","steps":["trace[1457093278] 'range keys from in-memory index tree' (duration: 164.516352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:14.471927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.674084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-05-21T11:29:14.472028Z","caller":"traceutil/trace.go:171","msg":"trace[354966865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25000; }","duration":"119.810097ms","start":"2026-05-21T11:29:14.352191Z","end":"2026-05-21T11:29:14.472001Z","steps":["trace[354966865] 'range keys from in-memory index tree' (duration: 119.476698ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:14.624848Z","caller":"traceutil/trace.go:171","msg":"trace[1828845394] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"146.807111ms","start":"2026-05-21T11:29:14.478003Z","end":"2026-05-21T11:29:14.62481Z","steps":["trace[1828845394] 'process raft request' (duration: 146.510203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.322203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340842778315913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T11:29:15.327038Z","caller":"traceutil/trace.go:171","msg":"trace[601029242] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"554.408052ms","start":"2026-05-21T11:29:14.772604Z","end":"2026-05-21T11:29:15.327012Z","steps":["trace[601029242] 'process raft request' (duration: 554.263269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.3272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:14.77255Z","time spent":"554.573896ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:15.606798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.516935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:15.606982Z","caller":"traceutil/trace.go:171","msg":"trace[114762703] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"805.748015ms","start":"2026-05-21T11:29:14.801208Z","end":"2026-05-21T11:29:15.606957Z","steps":["trace[114762703] 'process raft request' (duration: 598.890821ms)","trace[114762703] 'compare' (duration: 206.395312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:15.607094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:14.80119Z","time spent":"805.840988ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:29:15.607125Z","caller":"traceutil/trace.go:171","msg":"trace[1025393855] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"804.44676ms","start":"2026-05-21T11:29:14.802649Z","end":"2026-05-21T11:29:15.607096Z","steps":["trace[1025393855] 'process raft request' (duration: 804.244555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.607273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:14.80264Z","time spent":"804.559423ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:29:15.607981Z","caller":"traceutil/trace.go:171","msg":"trace[634191979] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"800.976745ms","start":"2026-05-21T11:29:14.806984Z","end":"2026-05-21T11:29:15.60796Z","steps":["trace[634191979] 'process raft request' (duration: 800.05322ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:15.608111Z","caller":"traceutil/trace.go:171","msg":"trace[1706443942] linearizableReadLoop","detail":"{readStateIndex:25820; appliedIndex:25815; }","duration":"786.284727ms","start":"2026-05-21T11:29:14.821808Z","end":"2026-05-21T11:29:15.608093Z","steps":["trace[1706443942] 'read index received' (duration: 505.037281ms)","trace[1706443942] 'applied index is now lower than readState.Index' (duration: 281.245836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:15.60813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:14.806955Z","time spent":"801.108059ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:15.608838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.452545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-21T11:29:15.608907Z","caller":"traceutil/trace.go:171","msg":"trace[451978369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25006; }","duration":"493.558658ms","start":"2026-05-21T11:29:15.115333Z","end":"2026-05-21T11:29:15.608892Z","steps":["trace[451978369] 'agreement among raft nodes before linearized reading' (duration: 493.392854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.608946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.115312Z","time spent":"493.627149ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T11:29:15.609037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.998966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-21T11:29:15.609115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.827842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:15.609118Z","caller":"traceutil/trace.go:171","msg":"trace[1962213112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25006; }","duration":"218.077188ms","start":"2026-05-21T11:29:15.390995Z","end":"2026-05-21T11:29:15.609072Z","steps":["trace[1962213112] 'agreement among raft nodes before linearized reading' (duration: 217.866842ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:15.609155Z","caller":"traceutil/trace.go:171","msg":"trace[1926579170] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25006; }","duration":"256.899614ms","start":"2026-05-21T11:29:15.352244Z","end":"2026-05-21T11:29:15.609143Z","steps":["trace[1926579170] 'agreement among raft nodes before linearized reading' (duration: 256.835682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.609374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.468201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-21T11:29:15.609412Z","caller":"traceutil/trace.go:171","msg":"trace[1261999111] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25006; }","duration":"349.535183ms","start":"2026-05-21T11:29:15.259866Z","end":"2026-05-21T11:29:15.609401Z","steps":["trace[1261999111] 'agreement among raft nodes before linearized reading' (duration: 349.43166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.609445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.259848Z","time spent":"349.589325ms","remote":"127.0.0.1:47004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-21T11:29:15.609474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.710968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-21T11:29:15.609518Z","caller":"traceutil/trace.go:171","msg":"trace[1406356340] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833; range_end:; response_count:1; response_revision:25006; }","duration":"132.819742ms","start":"2026-05-21T11:29:15.476685Z","end":"2026-05-21T11:29:15.609504Z","steps":["trace[1406356340] 'agreement among raft nodes before linearized reading' (duration: 132.676928ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:15.608238Z","caller":"traceutil/trace.go:171","msg":"trace[1508982878] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"393.416675ms","start":"2026-05-21T11:29:15.214811Z","end":"2026-05-21T11:29:15.608228Z","steps":["trace[1508982878] 'process raft request' (duration: 393.080716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.609674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.214783Z","time spent":"394.865605ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:15.608378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.570534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:15.609802Z","caller":"traceutil/trace.go:171","msg":"trace[169472310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25006; }","duration":"788.031364ms","start":"2026-05-21T11:29:14.821762Z","end":"2026-05-21T11:29:15.609793Z","steps":["trace[169472310] 'agreement among raft nodes before linearized reading' (duration: 786.587315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:15.609819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:14.821743Z","time spent":"788.070805ms","remote":"127.0.0.1:48290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T11:29:15.609828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.145386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-21T11:29:15.609869Z","caller":"traceutil/trace.go:171","msg":"trace[1274217614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25006; }","duration":"191.218299ms","start":"2026-05-21T11:29:15.418638Z","end":"2026-05-21T11:29:15.609857Z","steps":["trace[1274217614] 'agreement among raft nodes before linearized reading' (duration: 191.104696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.021887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.581579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:16.022015Z","caller":"traceutil/trace.go:171","msg":"trace[123680740] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"409.395939ms","start":"2026-05-21T11:29:15.6126Z","end":"2026-05-21T11:29:16.021995Z","steps":["trace[123680740] 'process raft request' (duration: 171.619885ms)","trace[123680740] 'compare' (duration: 237.081825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:16.022074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.612582Z","time spent":"409.463831ms","remote":"127.0.0.1:48598","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-05-21T11:29:16.034178Z","caller":"traceutil/trace.go:171","msg":"trace[2071763644] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"420.739809ms","start":"2026-05-21T11:29:15.613414Z","end":"2026-05-21T11:29:16.034153Z","steps":["trace[2071763644] 'process raft request' (duration: 420.519293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.03432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.613381Z","time spent":"420.864511ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:29:16.034289Z","caller":"traceutil/trace.go:171","msg":"trace[1880903040] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"420.715678ms","start":"2026-05-21T11:29:15.613547Z","end":"2026-05-21T11:29:16.034263Z","steps":["trace[1880903040] 'process raft request' (duration: 420.512882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.034471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.613527Z","time spent":"420.894183ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:29:16.034773Z","caller":"traceutil/trace.go:171","msg":"trace[773950624] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"376.191217ms","start":"2026-05-21T11:29:15.658567Z","end":"2026-05-21T11:29:16.034759Z","steps":["trace[773950624] 'process raft request' (duration: 375.54809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.034848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.658544Z","time spent":"376.266329ms","remote":"127.0.0.1:48598","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-05-21T11:29:16.034926Z","caller":"traceutil/trace.go:171","msg":"trace[602388557] linearizableReadLoop","detail":"{readStateIndex:25823; appliedIndex:25820; }","duration":"420.650406ms","start":"2026-05-21T11:29:15.614246Z","end":"2026-05-21T11:29:16.034896Z","steps":["trace[602388557] 'read index received' (duration: 169.987391ms)","trace[602388557] 'applied index is now lower than readState.Index' (duration: 250.661724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:16.03507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.820631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-21T11:29:16.035108Z","caller":"traceutil/trace.go:171","msg":"trace[699809645] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25010; }","duration":"420.885503ms","start":"2026-05-21T11:29:15.614213Z","end":"2026-05-21T11:29:16.035098Z","steps":["trace[699809645] 'agreement among raft nodes before linearized reading' (duration: 420.76294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.035131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.614201Z","time spent":"420.924664ms","remote":"127.0.0.1:48410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1334,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-05-21T11:29:16.253926Z","caller":"traceutil/trace.go:171","msg":"trace[283283814] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"468.145807ms","start":"2026-05-21T11:29:15.785754Z","end":"2026-05-21T11:29:16.2539Z","steps":["trace[283283814] 'process raft request' (duration: 467.822098ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.253913Z","caller":"traceutil/trace.go:171","msg":"trace[1113157646] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25824; }","duration":"218.896361ms","start":"2026-05-21T11:29:16.034993Z","end":"2026-05-21T11:29:16.253889Z","steps":["trace[1113157646] 'read index received' (duration: 218.559112ms)","trace[1113157646] 'applied index is now lower than readState.Index' (duration: 336.279µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:16.254025Z","caller":"traceutil/trace.go:171","msg":"trace[1287805815] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"467.488169ms","start":"2026-05-21T11:29:15.786522Z","end":"2026-05-21T11:29:16.25401Z","steps":["trace[1287805815] 'process raft request' (duration: 467.240462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.254069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.785732Z","time spent":"468.26439ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:16.254095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.839383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:16.254076Z","caller":"traceutil/trace.go:171","msg":"trace[1098147243] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"332.184331ms","start":"2026-05-21T11:29:15.921861Z","end":"2026-05-21T11:29:16.254045Z","steps":["trace[1098147243] 'process raft request' (duration: 331.974255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.254126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.786503Z","time spent":"467.556971ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:16.254095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.827738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-21T11:29:16.254219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.172814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-21T11:29:16.254245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.518009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-05-21T11:29:16.254247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.921844Z","time spent":"332.317505ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:16.254267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.489808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:29:16.254139Z","caller":"traceutil/trace.go:171","msg":"trace[1049090024] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25013; }","duration":"579.931426ms","start":"2026-05-21T11:29:15.674195Z","end":"2026-05-21T11:29:16.254126Z","steps":["trace[1049090024] 'agreement among raft nodes before linearized reading' (duration: 579.842234ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.2543Z","caller":"traceutil/trace.go:171","msg":"trace[458927614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25013; }","duration":"576.293867ms","start":"2026-05-21T11:29:15.677976Z","end":"2026-05-21T11:29:16.25427Z","steps":["trace[458927614] 'agreement among raft nodes before linearized reading' (duration: 576.03885ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.254306Z","caller":"traceutil/trace.go:171","msg":"trace[864455408] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25013; }","duration":"215.57834ms","start":"2026-05-21T11:29:16.038706Z","end":"2026-05-21T11:29:16.254285Z","steps":["trace[864455408] 'agreement among raft nodes before linearized reading' (duration: 215.385215ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.254307Z","caller":"traceutil/trace.go:171","msg":"trace[852565275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25013; }","duration":"433.035143ms","start":"2026-05-21T11:29:15.821216Z","end":"2026-05-21T11:29:16.254251Z","steps":["trace[852565275] 'agreement among raft nodes before linearized reading' (duration: 432.771636ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.254325Z","caller":"traceutil/trace.go:171","msg":"trace[1180883106] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25013; }","duration":"539.753554ms","start":"2026-05-21T11:29:15.714554Z","end":"2026-05-21T11:29:16.254308Z","steps":["trace[1180883106] 'agreement among raft nodes before linearized reading' (duration: 539.438185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.254373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.821176Z","time spent":"433.179357ms","remote":"127.0.0.1:48302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T11:29:16.254334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.674183Z","time spent":"580.137051ms","remote":"127.0.0.1:53258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T11:29:16.254478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.714541Z","time spent":"539.872278ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T11:29:16.254164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.106679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T11:29:16.254611Z","caller":"traceutil/trace.go:171","msg":"trace[1886871711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25013; }","duration":"460.556611ms","start":"2026-05-21T11:29:15.794042Z","end":"2026-05-21T11:29:16.254599Z","steps":["trace[1886871711] 'agreement among raft nodes before linearized reading' (duration: 459.979405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.25465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.794002Z","time spent":"460.635723ms","remote":"127.0.0.1:48598","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-05-21T11:29:16.254348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:15.677926Z","time spent":"576.411221ms","remote":"127.0.0.1:48598","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-05-21T11:29:16.494533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.688424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:16.494621Z","caller":"traceutil/trace.go:171","msg":"trace[2016024676] linearizableReadLoop","detail":"{readStateIndex:25831; appliedIndex:25830; }","duration":"147.186842ms","start":"2026-05-21T11:29:16.347417Z","end":"2026-05-21T11:29:16.494603Z","steps":["trace[2016024676] 'read index received' (duration: 9.272612ms)","trace[2016024676] 'applied index is now lower than readState.Index' (duration: 137.91304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:16.494748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.328096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-21T11:29:16.494766Z","caller":"traceutil/trace.go:171","msg":"trace[2013521376] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25017; }","duration":"147.369107ms","start":"2026-05-21T11:29:16.34739Z","end":"2026-05-21T11:29:16.494759Z","steps":["trace[2013521376] 'agreement among raft nodes before linearized reading' (duration: 147.250034ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:16.494787Z","caller":"traceutil/trace.go:171","msg":"trace[998657214] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"154.791829ms","start":"2026-05-21T11:29:16.339972Z","end":"2026-05-21T11:29:16.494764Z","steps":["trace[998657214] 'process raft request' (duration: 16.787407ms)","trace[998657214] 'compare' (duration: 137.489047ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:16.798484Z","caller":"traceutil/trace.go:171","msg":"trace[1345089699] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"296.659055ms","start":"2026-05-21T11:29:16.501793Z","end":"2026-05-21T11:29:16.798452Z","steps":["trace[1345089699] 'process raft request' (duration: 289.108589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.798714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.629919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-21T11:29:16.798509Z","caller":"traceutil/trace.go:171","msg":"trace[1897801946] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25831; }","duration":"184.381113ms","start":"2026-05-21T11:29:16.61403Z","end":"2026-05-21T11:29:16.798411Z","steps":["trace[1897801946] 'read index received' (duration: 176.880809ms)","trace[1897801946] 'applied index is now lower than readState.Index' (duration: 7.499274ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:16.798768Z","caller":"traceutil/trace.go:171","msg":"trace[32402779] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e85d8038-e657-4b1f-98c2-89b1ff45e833; range_end:; response_count:1; response_revision:25018; }","duration":"184.779203ms","start":"2026-05-21T11:29:16.613975Z","end":"2026-05-21T11:29:16.798754Z","steps":["trace[32402779] 'agreement among raft nodes before linearized reading' (duration: 184.548837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:16.798946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.141099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:29:16.798998Z","caller":"traceutil/trace.go:171","msg":"trace[1593049140] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25018; }","duration":"172.234342ms","start":"2026-05-21T11:29:16.626751Z","end":"2026-05-21T11:29:16.798986Z","steps":["trace[1593049140] 'agreement among raft nodes before linearized reading' (duration: 172.102399ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:17.823529Z","caller":"traceutil/trace.go:171","msg":"trace[1445869448] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"211.378057ms","start":"2026-05-21T11:29:17.61212Z","end":"2026-05-21T11:29:17.823498Z","steps":["trace[1445869448] 'process raft request' (duration: 152.174608ms)","trace[1445869448] 'compare' (duration: 58.886741ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:17.82363Z","caller":"traceutil/trace.go:171","msg":"trace[1274355300] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"208.56041ms","start":"2026-05-21T11:29:17.615029Z","end":"2026-05-21T11:29:17.823589Z","steps":["trace[1274355300] 'process raft request' (duration: 208.270872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.325068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.65479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T11:29:18.325164Z","caller":"traceutil/trace.go:171","msg":"trace[96593054] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25836; }","duration":"331.170953ms","start":"2026-05-21T11:29:17.993976Z","end":"2026-05-21T11:29:18.325146Z","steps":["trace[96593054] 'read index received' (duration: 89.067301ms)","trace[96593054] 'applied index is now lower than readState.Index' (duration: 242.101902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:18.32557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.578495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:29:18.325614Z","caller":"traceutil/trace.go:171","msg":"trace[760298178] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25022; }","duration":"331.694697ms","start":"2026-05-21T11:29:17.993908Z","end":"2026-05-21T11:29:18.325602Z","steps":["trace[760298178] 'agreement among raft nodes before linearized reading' (duration: 331.348778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.325752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:17.993887Z","time spent":"331.821821ms","remote":"127.0.0.1:59614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":38,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-05-21T11:29:18.420494Z","caller":"traceutil/trace.go:171","msg":"trace[1723543474] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"380.304039ms","start":"2026-05-21T11:29:18.040164Z","end":"2026-05-21T11:29:18.420469Z","steps":["trace[1723543474] 'process raft request' (duration: 380.127894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.420645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:18.040147Z","time spent":"380.418983ms","remote":"127.0.0.1:48598","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-05-21T11:29:18.514712Z","caller":"traceutil/trace.go:171","msg":"trace[1307132314] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25837; }","duration":"189.501332ms","start":"2026-05-21T11:29:18.325188Z","end":"2026-05-21T11:29:18.514689Z","steps":["trace[1307132314] 'read index received' (duration: 95.095745ms)","trace[1307132314] 'applied index is now lower than readState.Index' (duration: 94.404337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:18.514817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.57805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T11:29:18.514853Z","caller":"traceutil/trace.go:171","msg":"trace[484629963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25026; }","duration":"244.662542ms","start":"2026-05-21T11:29:18.27018Z","end":"2026-05-21T11:29:18.514843Z","steps":["trace[484629963] 'agreement among raft nodes before linearized reading' (duration: 244.521918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.514916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.790362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-21T11:29:18.51492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.31902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-21T11:29:18.514955Z","caller":"traceutil/trace.go:171","msg":"trace[2077640663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25026; }","duration":"476.869844ms","start":"2026-05-21T11:29:18.038072Z","end":"2026-05-21T11:29:18.514942Z","steps":["trace[2077640663] 'agreement among raft nodes before linearized reading' (duration: 476.747751ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:18.515Z","caller":"traceutil/trace.go:171","msg":"trace[990804899] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"255.027743ms","start":"2026-05-21T11:29:18.259964Z","end":"2026-05-21T11:29:18.514992Z","steps":["trace[990804899] 'process raft request' (duration: 254.607952ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:18.514992Z","caller":"traceutil/trace.go:171","msg":"trace[943576954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25026; }","duration":"476.419052ms","start":"2026-05-21T11:29:18.038553Z","end":"2026-05-21T11:29:18.514972Z","steps":["trace[943576954] 'agreement among raft nodes before linearized reading' (duration: 476.232377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.515036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:18.038541Z","time spent":"476.487224ms","remote":"127.0.0.1:48598","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-05-21T11:29:18.515072Z","caller":"traceutil/trace.go:171","msg":"trace[1030245113] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"254.92947ms","start":"2026-05-21T11:29:18.260137Z","end":"2026-05-21T11:29:18.515067Z","steps":["trace[1030245113] 'process raft request' (duration: 254.497929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.5151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.378028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-21T11:29:18.514995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:18.038047Z","time spent":"476.937396ms","remote":"127.0.0.1:48598","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-05-21T11:29:18.515148Z","caller":"traceutil/trace.go:171","msg":"trace[522528040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25026; }","duration":"488.45209ms","start":"2026-05-21T11:29:18.026684Z","end":"2026-05-21T11:29:18.515136Z","steps":["trace[522528040] 'agreement among raft nodes before linearized reading' (duration: 488.335917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.515189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.863213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-05-21T11:29:18.515188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:18.02667Z","time spent":"488.506731ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T11:29:18.515211Z","caller":"traceutil/trace.go:171","msg":"trace[953581135] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25026; }","duration":"423.910474ms","start":"2026-05-21T11:29:18.091292Z","end":"2026-05-21T11:29:18.515202Z","steps":["trace[953581135] 'agreement among raft nodes before linearized reading' (duration: 423.843052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:18.515228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:18.091265Z","time spent":"423.957975ms","remote":"127.0.0.1:47004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-21T11:29:18.515294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.979962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-21T11:29:18.515363Z","caller":"traceutil/trace.go:171","msg":"trace[1829672569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25026; }","duration":"177.088035ms","start":"2026-05-21T11:29:18.338258Z","end":"2026-05-21T11:29:18.515346Z","steps":["trace[1829672569] 'agreement among raft nodes before linearized reading' (duration: 176.873199ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:18.515404Z","caller":"traceutil/trace.go:171","msg":"trace[617310189] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"255.438984ms","start":"2026-05-21T11:29:18.259949Z","end":"2026-05-21T11:29:18.515388Z","steps":["trace[617310189] 'process raft request' (duration: 199.623166ms)","trace[617310189] 'compare' (duration: 54.708448ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:21.50404Z","caller":"traceutil/trace.go:171","msg":"trace[1651020197] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"125.966315ms","start":"2026-05-21T11:29:21.378056Z","end":"2026-05-21T11:29:21.504023Z","steps":["trace[1651020197] 'process raft request' (duration: 125.931874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:21.504197Z","caller":"traceutil/trace.go:171","msg":"trace[1675913750] linearizableReadLoop","detail":"{readStateIndex:25863; appliedIndex:25862; }","duration":"241.413013ms","start":"2026-05-21T11:29:21.262765Z","end":"2026-05-21T11:29:21.504178Z","steps":["trace[1675913750] 'read index received' (duration: 240.623472ms)","trace[1675913750] 'applied index is now lower than readState.Index' (duration: 787.291µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:21.504261Z","caller":"traceutil/trace.go:171","msg":"trace[1795953311] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"248.387103ms","start":"2026-05-21T11:29:21.255868Z","end":"2026-05-21T11:29:21.504255Z","steps":["trace[1795953311] 'process raft request' (duration: 246.999665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:21.504346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.573067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-21T11:29:21.50438Z","caller":"traceutil/trace.go:171","msg":"trace[389902237] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25049; }","duration":"241.656289ms","start":"2026-05-21T11:29:21.262713Z","end":"2026-05-21T11:29:21.50437Z","steps":["trace[389902237] 'agreement among raft nodes before linearized reading' (duration: 241.521406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.345417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.56259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-05-21T11:29:24.345517Z","caller":"traceutil/trace.go:171","msg":"trace[1706583613] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25083; }","duration":"151.710864ms","start":"2026-05-21T11:29:24.193782Z","end":"2026-05-21T11:29:24.345493Z","steps":["trace[1706583613] 'range keys from in-memory index tree' (duration: 151.387465ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:24.61253Z","caller":"traceutil/trace.go:171","msg":"trace[2088621929] linearizableReadLoop","detail":"{readStateIndex:25902; appliedIndex:25901; }","duration":"234.065623ms","start":"2026-05-21T11:29:24.378439Z","end":"2026-05-21T11:29:24.612505Z","steps":["trace[2088621929] 'read index received' (duration: 233.914589ms)","trace[2088621929] 'applied index is now lower than readState.Index' (duration: 149.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:24.612619Z","caller":"traceutil/trace.go:171","msg":"trace[133256950] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"261.291313ms","start":"2026-05-21T11:29:24.351298Z","end":"2026-05-21T11:29:24.61259Z","steps":["trace[133256950] 'process raft request' (duration: 261.006346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.612688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.224187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:24.612738Z","caller":"traceutil/trace.go:171","msg":"trace[312938448] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25084; }","duration":"234.33437ms","start":"2026-05-21T11:29:24.378379Z","end":"2026-05-21T11:29:24.612714Z","steps":["trace[312938448] 'agreement among raft nodes before linearized reading' (duration: 234.243187ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:24.972332Z","caller":"traceutil/trace.go:171","msg":"trace[746522225] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"531.844169ms","start":"2026-05-21T11:29:24.440458Z","end":"2026-05-21T11:29:24.972303Z","steps":["trace[746522225] 'process raft request' (duration: 531.620683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.972498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.44044Z","time spent":"531.972822ms","remote":"127.0.0.1:48598","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-05-21T11:29:24.972837Z","caller":"traceutil/trace.go:171","msg":"trace[1090796440] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"440.163476ms","start":"2026-05-21T11:29:24.53265Z","end":"2026-05-21T11:29:24.972814Z","steps":["trace[1090796440] 'process raft request' (duration: 439.594581ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:24.973191Z","caller":"traceutil/trace.go:171","msg":"trace[2108551991] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"436.788515ms","start":"2026-05-21T11:29:24.536377Z","end":"2026-05-21T11:29:24.973165Z","steps":["trace[2108551991] 'process raft request' (duration: 436.349263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.973254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.532636Z","time spent":"440.523286ms","remote":"127.0.0.1:48598","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-05-21T11:29:24.973287Z","caller":"traceutil/trace.go:171","msg":"trace[2091707875] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25902; }","duration":"360.675966ms","start":"2026-05-21T11:29:24.612598Z","end":"2026-05-21T11:29:24.973274Z","steps":["trace[2091707875] 'read index received' (duration: 359.487234ms)","trace[2091707875] 'applied index is now lower than readState.Index' (duration: 1.187702ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:24.973352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.53635Z","time spent":"436.920889ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:24.973539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.488409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-21T11:29:24.973606Z","caller":"traceutil/trace.go:171","msg":"trace[172071013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25088; }","duration":"428.559881ms","start":"2026-05-21T11:29:24.545016Z","end":"2026-05-21T11:29:24.973576Z","steps":["trace[172071013] 'agreement among raft nodes before linearized reading' (duration: 428.378216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.973642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.545005Z","time spent":"428.625962ms","remote":"127.0.0.1:48598","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-05-21T11:29:24.973664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.469137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-21T11:29:24.973637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.438172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T11:29:24.97373Z","caller":"traceutil/trace.go:171","msg":"trace[945485426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25088; }","duration":"426.569566ms","start":"2026-05-21T11:29:24.547138Z","end":"2026-05-21T11:29:24.973707Z","steps":["trace[945485426] 'agreement among raft nodes before linearized reading' (duration: 426.219357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.973785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.826884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-21T11:29:24.973804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.547129Z","time spent":"426.656458ms","remote":"127.0.0.1:48598","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-05-21T11:29:24.973813Z","caller":"traceutil/trace.go:171","msg":"trace[937708933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25088; }","duration":"433.892356ms","start":"2026-05-21T11:29:24.539913Z","end":"2026-05-21T11:29:24.973805Z","steps":["trace[937708933] 'agreement among raft nodes before linearized reading' (duration: 433.808884ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:24.973763Z","caller":"traceutil/trace.go:171","msg":"trace[648350382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25088; }","duration":"432.546599ms","start":"2026-05-21T11:29:24.541144Z","end":"2026-05-21T11:29:24.97369Z","steps":["trace[648350382] 'agreement among raft nodes before linearized reading' (duration: 432.408565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.973841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.539899Z","time spent":"433.936397ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T11:29:24.973867Z","caller":"traceutil/trace.go:171","msg":"trace[594465011] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"436.560368ms","start":"2026-05-21T11:29:24.537272Z","end":"2026-05-21T11:29:24.973832Z","steps":["trace[594465011] 'process raft request' (duration: 435.769916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.97387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.541121Z","time spent":"432.733734ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T11:29:24.974022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.537258Z","time spent":"436.6649ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:24.974037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.740671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-21T11:29:24.974118Z","caller":"traceutil/trace.go:171","msg":"trace[1858940857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25088; }","duration":"426.867504ms","start":"2026-05-21T11:29:24.547233Z","end":"2026-05-21T11:29:24.9741Z","steps":["trace[1858940857] 'agreement among raft nodes before linearized reading' (duration: 426.579856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.974183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.547226Z","time spent":"426.942216ms","remote":"127.0.0.1:48598","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-05-21T11:29:24.974544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.23446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:29:24.974638Z","caller":"traceutil/trace.go:171","msg":"trace[599146425] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25088; }","duration":"425.384685ms","start":"2026-05-21T11:29:24.549225Z","end":"2026-05-21T11:29:24.97461Z","steps":["trace[599146425] 'agreement among raft nodes before linearized reading' (duration: 424.545871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.974879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:24.549212Z","time spent":"425.655272ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":78,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-21T11:29:24.987115Z","caller":"traceutil/trace.go:171","msg":"trace[1272159379] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"271.217444ms","start":"2026-05-21T11:29:24.715877Z","end":"2026-05-21T11:29:24.987095Z","steps":["trace[1272159379] 'process raft request' (duration: 271.09069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:24.987328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.552364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-21T11:29:24.987346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.226218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-21T11:29:24.98739Z","caller":"traceutil/trace.go:171","msg":"trace[436925642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25089; }","duration":"164.670707ms","start":"2026-05-21T11:29:24.8227Z","end":"2026-05-21T11:29:24.987371Z","steps":["trace[436925642] 'agreement among raft nodes before linearized reading' (duration: 164.505433ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:24.987392Z","caller":"traceutil/trace.go:171","msg":"trace[1093699956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25089; }","duration":"152.328961ms","start":"2026-05-21T11:29:24.835049Z","end":"2026-05-21T11:29:24.987378Z","steps":["trace[1093699956] 'agreement among raft nodes before linearized reading' (duration: 152.177687ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:44.052074Z","caller":"traceutil/trace.go:171","msg":"trace[54231648] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"146.906235ms","start":"2026-05-21T11:29:43.905132Z","end":"2026-05-21T11:29:44.052039Z","steps":["trace[54231648] 'process raft request' (duration: 146.735701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:44.34142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.553195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:44.341558Z","caller":"traceutil/trace.go:171","msg":"trace[1762036738] linearizableReadLoop","detail":"{readStateIndex:26076; appliedIndex:26074; }","duration":"104.204452ms","start":"2026-05-21T11:29:44.237338Z","end":"2026-05-21T11:29:44.341542Z","steps":["trace[1762036738] 'read index received' (duration: 35.37µs)","trace[1762036738] 'applied index is now lower than readState.Index' (duration: 104.168472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:44.341661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:44.341697Z","caller":"traceutil/trace.go:171","msg":"trace[534242004] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25254; }","duration":"104.372346ms","start":"2026-05-21T11:29:44.237301Z","end":"2026-05-21T11:29:44.341673Z","steps":["trace[534242004] 'agreement among raft nodes before linearized reading' (duration: 104.293844ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:44.341689Z","caller":"traceutil/trace.go:171","msg":"trace[41121641] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"433.589158ms","start":"2026-05-21T11:29:43.908076Z","end":"2026-05-21T11:29:44.341666Z","steps":["trace[41121641] 'process raft request' (duration: 240.711445ms)","trace[41121641] 'compare' (duration: 192.478893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:44.341807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:43.908068Z","time spent":"433.67424ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:29:44.341785Z","caller":"traceutil/trace.go:171","msg":"trace[494282512] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"317.167902ms","start":"2026-05-21T11:29:44.024518Z","end":"2026-05-21T11:29:44.341686Z","steps":["trace[494282512] 'process raft request' (duration: 316.964406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:44.341968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:44.024495Z","time spent":"317.361877ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:44.617624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.161361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-21T11:29:44.61769Z","caller":"traceutil/trace.go:171","msg":"trace[1417636125] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25254; }","duration":"140.281755ms","start":"2026-05-21T11:29:44.477394Z","end":"2026-05-21T11:29:44.617676Z","steps":["trace[1417636125] 'count revisions from in-memory index tree' (duration: 140.078678ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:44.690708Z","caller":"traceutil/trace.go:171","msg":"trace[1609281566] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"136.330436ms","start":"2026-05-21T11:29:44.554354Z","end":"2026-05-21T11:29:44.690685Z","steps":["trace[1609281566] 'process raft request' (duration: 136.183602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.166749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.889503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-05-21T11:29:45.166834Z","caller":"traceutil/trace.go:171","msg":"trace[1851101214] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25256; }","duration":"286.029097ms","start":"2026-05-21T11:29:44.880783Z","end":"2026-05-21T11:29:45.166812Z","steps":["trace[1851101214] 'range keys from in-memory index tree' (duration: 285.668176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.166834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.638693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T11:29:45.166925Z","caller":"traceutil/trace.go:171","msg":"trace[2058434869] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25256; }","duration":"183.761236ms","start":"2026-05-21T11:29:44.983144Z","end":"2026-05-21T11:29:45.166905Z","steps":["trace[2058434869] 'count revisions from in-memory index tree' (duration: 183.53435ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.170475Z","caller":"traceutil/trace.go:171","msg":"trace[1066251659] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"127.970969ms","start":"2026-05-21T11:29:45.042489Z","end":"2026-05-21T11:29:45.170459Z","steps":["trace[1066251659] 'process raft request' (duration: 127.843816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.414974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.285639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:45.415178Z","caller":"traceutil/trace.go:171","msg":"trace[2143307354] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26078; }","duration":"333.778174ms","start":"2026-05-21T11:29:45.081384Z","end":"2026-05-21T11:29:45.415162Z","steps":["trace[2143307354] 'read index received' (duration: 88.98628ms)","trace[2143307354] 'applied index is now lower than readState.Index' (duration: 244.790964ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:45.415242Z","caller":"traceutil/trace.go:171","msg":"trace[447009975] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"365.920287ms","start":"2026-05-21T11:29:45.049293Z","end":"2026-05-21T11:29:45.415214Z","steps":["trace[447009975] 'process raft request' (duration: 365.828834ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.415298Z","caller":"traceutil/trace.go:171","msg":"trace[2124841248] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"370.365718ms","start":"2026-05-21T11:29:45.044907Z","end":"2026-05-21T11:29:45.415272Z","steps":["trace[2124841248] 'process raft request' (duration: 231.692909ms)","trace[2124841248] 'compare' (duration: 138.161656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:45.415349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.961568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-21T11:29:45.415371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.873026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-21T11:29:45.415398Z","caller":"traceutil/trace.go:171","msg":"trace[1431830140] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25260; }","duration":"132.074481ms","start":"2026-05-21T11:29:45.283315Z","end":"2026-05-21T11:29:45.415389Z","steps":["trace[1431830140] 'agreement among raft nodes before linearized reading' (duration: 131.971358ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.415407Z","caller":"traceutil/trace.go:171","msg":"trace[1190539451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25260; }","duration":"330.924217ms","start":"2026-05-21T11:29:45.08447Z","end":"2026-05-21T11:29:45.415394Z","steps":["trace[1190539451] 'agreement among raft nodes before linearized reading' (duration: 330.791853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.415401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.049267Z","time spent":"366.054441ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:45.415403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.044891Z","time spent":"370.450081ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:45.415443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.084463Z","time spent":"330.972358ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T11:29:45.41551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.631922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-21T11:29:45.415568Z","caller":"traceutil/trace.go:171","msg":"trace[1217659336] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25260; }","duration":"240.715064ms","start":"2026-05-21T11:29:45.174838Z","end":"2026-05-21T11:29:45.415553Z","steps":["trace[1217659336] 'agreement among raft nodes before linearized reading' (duration: 240.478978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.415723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.130263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T11:29:45.415759Z","caller":"traceutil/trace.go:171","msg":"trace[2028102997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25260; }","duration":"327.177234ms","start":"2026-05-21T11:29:45.088574Z","end":"2026-05-21T11:29:45.415751Z","steps":["trace[2028102997] 'agreement among raft nodes before linearized reading' (duration: 327.076292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.415786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.088569Z","time spent":"327.211135ms","remote":"127.0.0.1:48598","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-05-21T11:29:45.415251Z","caller":"traceutil/trace.go:171","msg":"trace[2106334207] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"366.790542ms","start":"2026-05-21T11:29:45.048417Z","end":"2026-05-21T11:29:45.415208Z","steps":["trace[2106334207] 'process raft request' (duration: 366.647748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.415862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.660829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-21T11:29:45.416015Z","caller":"traceutil/trace.go:171","msg":"trace[1083351191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25260; }","duration":"324.154293ms","start":"2026-05-21T11:29:45.091832Z","end":"2026-05-21T11:29:45.415986Z","steps":["trace[1083351191] 'agreement among raft nodes before linearized reading' (duration: 323.465733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.416039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.048412Z","time spent":"367.558713ms","remote":"127.0.0.1:48598","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-05-21T11:29:45.416077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.70062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T11:29:45.416107Z","caller":"traceutil/trace.go:171","msg":"trace[1599143609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25260; }","duration":"334.771112ms","start":"2026-05-21T11:29:45.081327Z","end":"2026-05-21T11:29:45.416098Z","steps":["trace[1599143609] 'agreement among raft nodes before linearized reading' (duration: 333.901397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.4161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.091809Z","time spent":"324.274486ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T11:29:45.416133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.081303Z","time spent":"334.824803ms","remote":"127.0.0.1:48598","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-05-21T11:29:45.41589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.159292ms","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-05-21T11:29:45.416175Z","caller":"traceutil/trace.go:171","msg":"trace[612369807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25260; }","duration":"324.464831ms","start":"2026-05-21T11:29:45.091699Z","end":"2026-05-21T11:29:45.416164Z","steps":["trace[612369807] 'agreement among raft nodes before linearized reading' (duration: 324.111941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.416206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.091689Z","time spent":"324.507552ms","remote":"127.0.0.1:48598","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":"warn","ts":"2026-05-21T11:29:45.693528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.859733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:45.693819Z","caller":"traceutil/trace.go:171","msg":"trace[1905014787] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"272.449288ms","start":"2026-05-21T11:29:45.421352Z","end":"2026-05-21T11:29:45.693801Z","steps":["trace[1905014787] 'process raft request' (duration: 272.381766ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.693844Z","caller":"traceutil/trace.go:171","msg":"trace[359700279] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"272.721234ms","start":"2026-05-21T11:29:45.421097Z","end":"2026-05-21T11:29:45.693818Z","steps":["trace[359700279] 'process raft request' (duration: 272.541239ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.694012Z","caller":"traceutil/trace.go:171","msg":"trace[1618810499] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26082; }","duration":"272.757735ms","start":"2026-05-21T11:29:45.421241Z","end":"2026-05-21T11:29:45.693999Z","steps":["trace[1618810499] 'read index received' (duration: 125.418359ms)","trace[1618810499] 'applied index is now lower than readState.Index' (duration: 147.338226ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:45.694011Z","caller":"traceutil/trace.go:171","msg":"trace[1915799190] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"273.611569ms","start":"2026-05-21T11:29:45.420377Z","end":"2026-05-21T11:29:45.693988Z","steps":["trace[1915799190] 'process raft request' (duration: 126.197301ms)","trace[1915799190] 'compare' (duration: 146.727009ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:45.694139Z","caller":"traceutil/trace.go:171","msg":"trace[1376113988] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"272.677433ms","start":"2026-05-21T11:29:45.421282Z","end":"2026-05-21T11:29:45.69396Z","steps":["trace[1376113988] 'process raft request' (duration: 272.417996ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.694125Z","caller":"traceutil/trace.go:171","msg":"trace[832664291] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"272.470417ms","start":"2026-05-21T11:29:45.421543Z","end":"2026-05-21T11:29:45.694013Z","steps":["trace[832664291] 'process raft request' (duration: 272.22601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.69418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.932731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18624"} {"level":"info","ts":"2026-05-21T11:29:45.694237Z","caller":"traceutil/trace.go:171","msg":"trace[1493585094] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25265; }","duration":"273.031243ms","start":"2026-05-21T11:29:45.421192Z","end":"2026-05-21T11:29:45.694223Z","steps":["trace[1493585094] 'agreement among raft nodes before linearized reading' (duration: 272.848368ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.909905Z","caller":"traceutil/trace.go:171","msg":"trace[1342746450] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"322.947589ms","start":"2026-05-21T11:29:45.586919Z","end":"2026-05-21T11:29:45.909866Z","steps":["trace[1342746450] 'process raft request' (duration: 231.645446ms)","trace[1342746450] 'compare' (duration: 91.035836ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:45.909906Z","caller":"traceutil/trace.go:171","msg":"trace[112257771] linearizableReadLoop","detail":"{readStateIndex:26088; appliedIndex:26087; }","duration":"203.059271ms","start":"2026-05-21T11:29:45.706823Z","end":"2026-05-21T11:29:45.909883Z","steps":["trace[112257771] 'read index received' (duration: 111.706437ms)","trace[112257771] 'applied index is now lower than readState.Index' (duration: 91.351934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:45.910106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:29:45.586876Z","time spent":"323.133444ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:29:45.910192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.358579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:45.910171Z","caller":"traceutil/trace.go:171","msg":"trace[704549217] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"172.470539ms","start":"2026-05-21T11:29:45.737638Z","end":"2026-05-21T11:29:45.910108Z","steps":["trace[704549217] 'process raft request' (duration: 172.176252ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:45.910234Z","caller":"traceutil/trace.go:171","msg":"trace[1261533060] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25267; }","duration":"203.453741ms","start":"2026-05-21T11:29:45.706766Z","end":"2026-05-21T11:29:45.91022Z","steps":["trace[1261533060] 'agreement among raft nodes before linearized reading' (duration: 203.346769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:45.910379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.988585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:45.910425Z","caller":"traceutil/trace.go:171","msg":"trace[1913315429] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25267; }","duration":"126.082288ms","start":"2026-05-21T11:29:45.784331Z","end":"2026-05-21T11:29:45.910414Z","steps":["trace[1913315429] 'agreement among raft nodes before linearized reading' (duration: 125.992385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:46.241173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.778292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:29:46.241296Z","caller":"traceutil/trace.go:171","msg":"trace[1308797951] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"175.055209ms","start":"2026-05-21T11:29:46.066217Z","end":"2026-05-21T11:29:46.241272Z","steps":["trace[1308797951] 'process raft request' (duration: 36.092922ms)","trace[1308797951] 'compare' (duration: 138.346061ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:46.657416Z","caller":"traceutil/trace.go:171","msg":"trace[1424206319] linearizableReadLoop","detail":"{readStateIndex:26093; appliedIndex:26092; }","duration":"115.889531ms","start":"2026-05-21T11:29:46.541482Z","end":"2026-05-21T11:29:46.657371Z","steps":["trace[1424206319] 'read index received' (duration: 50.40691ms)","trace[1424206319] 'applied index is now lower than readState.Index' (duration: 65.481541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:46.65764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.136188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-21T11:29:46.657683Z","caller":"traceutil/trace.go:171","msg":"trace[1278423085] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25271; }","duration":"116.226961ms","start":"2026-05-21T11:29:46.541442Z","end":"2026-05-21T11:29:46.657669Z","steps":["trace[1278423085] 'agreement among raft nodes before linearized reading' (duration: 116.053626ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:46.657766Z","caller":"traceutil/trace.go:171","msg":"trace[2073545667] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"154.306145ms","start":"2026-05-21T11:29:46.503409Z","end":"2026-05-21T11:29:46.657716Z","steps":["trace[2073545667] 'process raft request' (duration: 88.538507ms)","trace[2073545667] 'compare' (duration: 65.300985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:29:47.106763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.756367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18624"} {"level":"info","ts":"2026-05-21T11:29:47.106865Z","caller":"traceutil/trace.go:171","msg":"trace[43558601] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25271; }","duration":"282.88524ms","start":"2026-05-21T11:29:46.823955Z","end":"2026-05-21T11:29:47.106841Z","steps":["trace[43558601] 'range keys from in-memory index tree' (duration: 282.593662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.106769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.918018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-21T11:29:47.106906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.133479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:29:47.107014Z","caller":"traceutil/trace.go:171","msg":"trace[216698000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25271; }","duration":"284.180265ms","start":"2026-05-21T11:29:46.822792Z","end":"2026-05-21T11:29:47.106972Z","steps":["trace[216698000] 'range keys from in-memory index tree' (duration: 283.849716ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:47.107246Z","caller":"traceutil/trace.go:171","msg":"trace[1118512669] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25271; }","duration":"172.377836ms","start":"2026-05-21T11:29:46.934706Z","end":"2026-05-21T11:29:47.107084Z","steps":["trace[1118512669] 'count revisions from in-memory index tree' (duration: 172.054038ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:47.39163Z","caller":"traceutil/trace.go:171","msg":"trace[2082370269] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"215.644173ms","start":"2026-05-21T11:29:47.17596Z","end":"2026-05-21T11:29:47.391604Z","steps":["trace[2082370269] 'process raft request' (duration: 215.451817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.799976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.531161ms","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-05-21T11:29:47.800058Z","caller":"traceutil/trace.go:171","msg":"trace[1785153877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25275; }","duration":"101.625773ms","start":"2026-05-21T11:29:47.698414Z","end":"2026-05-21T11:29:47.80004Z","steps":["trace[1785153877] 'range keys from in-memory index tree' (duration: 101.354015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.800221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.47786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T11:29:47.800282Z","caller":"traceutil/trace.go:171","msg":"trace[1581124191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25275; }","duration":"101.560741ms","start":"2026-05-21T11:29:47.698704Z","end":"2026-05-21T11:29:47.800265Z","steps":["trace[1581124191] 'range keys from in-memory index tree' (duration: 101.310394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.800443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.539678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T11:29:47.800497Z","caller":"traceutil/trace.go:171","msg":"trace[441819461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25275; }","duration":"102.64405ms","start":"2026-05-21T11:29:47.697837Z","end":"2026-05-21T11:29:47.800481Z","steps":["trace[441819461] 'range keys from in-memory index tree' (duration: 102.334223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.800522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.696302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-21T11:29:47.800555Z","caller":"traceutil/trace.go:171","msg":"trace[518760053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25275; }","duration":"102.767734ms","start":"2026-05-21T11:29:47.697777Z","end":"2026-05-21T11:29:47.800545Z","steps":["trace[518760053] 'range keys from in-memory index tree' (duration: 102.484787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:47.800596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.759227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-21T11:29:47.800682Z","caller":"traceutil/trace.go:171","msg":"trace[1363839464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25275; }","duration":"101.87956ms","start":"2026-05-21T11:29:47.698768Z","end":"2026-05-21T11:29:47.800648Z","steps":["trace[1363839464] 'range keys from in-memory index tree' (duration: 101.580422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:47.92212Z","caller":"traceutil/trace.go:171","msg":"trace[149148699] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"117.35075ms","start":"2026-05-21T11:29:47.804741Z","end":"2026-05-21T11:29:47.922092Z","steps":["trace[149148699] 'process raft request' (duration: 117.183615ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:48.070599Z","caller":"traceutil/trace.go:171","msg":"trace[339917272] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"265.675173ms","start":"2026-05-21T11:29:47.804901Z","end":"2026-05-21T11:29:48.070576Z","steps":["trace[339917272] 'process raft request' (duration: 219.95202ms)","trace[339917272] 'compare' (duration: 45.432145ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:48.07095Z","caller":"traceutil/trace.go:171","msg":"trace[1669232125] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"266.030282ms","start":"2026-05-21T11:29:47.804903Z","end":"2026-05-21T11:29:48.070933Z","steps":["trace[1669232125] 'process raft request' (duration: 265.532579ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:48.070982Z","caller":"traceutil/trace.go:171","msg":"trace[159576556] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"154.695786ms","start":"2026-05-21T11:29:47.916257Z","end":"2026-05-21T11:29:48.070952Z","steps":["trace[159576556] 'process raft request' (duration: 154.48079ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:48.070985Z","caller":"traceutil/trace.go:171","msg":"trace[636438873] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"265.895869ms","start":"2026-05-21T11:29:47.805026Z","end":"2026-05-21T11:29:48.070922Z","steps":["trace[636438873] 'process raft request' (duration: 265.53843ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:48.071054Z","caller":"traceutil/trace.go:171","msg":"trace[1889915550] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"265.634511ms","start":"2026-05-21T11:29:47.805408Z","end":"2026-05-21T11:29:48.071043Z","steps":["trace[1889915550] 'process raft request' (duration: 265.232211ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:48.070983Z","caller":"traceutil/trace.go:171","msg":"trace[1054690529] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"154.86256ms","start":"2026-05-21T11:29:47.916086Z","end":"2026-05-21T11:29:48.070949Z","steps":["trace[1054690529] 'process raft request' (duration: 154.606973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:29:48.190748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.92303ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T11:29:48.362456Z","caller":"traceutil/trace.go:171","msg":"trace[36072711] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"109.384534ms","start":"2026-05-21T11:29:48.253044Z","end":"2026-05-21T11:29:48.362428Z","steps":["trace[36072711] 'process raft request' (duration: 109.183299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:50.576735Z","caller":"traceutil/trace.go:171","msg":"trace[1489536196] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"131.254848ms","start":"2026-05-21T11:29:50.44544Z","end":"2026-05-21T11:29:50.576695Z","steps":["trace[1489536196] 'process raft request' (duration: 62.92795ms)","trace[1489536196] 'compare' (duration: 67.99933ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:29:56.543477Z","caller":"traceutil/trace.go:171","msg":"trace[469707610] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"111.608404ms","start":"2026-05-21T11:29:56.431852Z","end":"2026-05-21T11:29:56.543461Z","steps":["trace[469707610] 'process raft request' (duration: 111.233894ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:29:59.103655Z","caller":"traceutil/trace.go:171","msg":"trace[445118807] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"286.493139ms","start":"2026-05-21T11:29:58.817133Z","end":"2026-05-21T11:29:59.103626Z","steps":["trace[445118807] 'process raft request' (duration: 286.302743ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:03.593392Z","caller":"traceutil/trace.go:171","msg":"trace[1965149222] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"145.27511ms","start":"2026-05-21T11:30:03.448091Z","end":"2026-05-21T11:30:03.593366Z","steps":["trace[1965149222] 'process raft request' (duration: 145.120265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:03.79619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.695299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:30:03.796365Z","caller":"traceutil/trace.go:171","msg":"trace[1064434220] linearizableReadLoop","detail":"{readStateIndex:26232; appliedIndex:26230; }","duration":"139.043741ms","start":"2026-05-21T11:30:03.657306Z","end":"2026-05-21T11:30:03.796349Z","steps":["trace[1064434220] 'read index received' (duration: 35.000452ms)","trace[1064434220] 'applied index is now lower than readState.Index' (duration: 104.042379ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:03.79636Z","caller":"traceutil/trace.go:171","msg":"trace[1135192877] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"202.587978ms","start":"2026-05-21T11:30:03.593748Z","end":"2026-05-21T11:30:03.796336Z","steps":["trace[1135192877] 'process raft request' (duration: 98.658073ms)","trace[1135192877] 'compare' (duration: 103.434582ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:03.796597Z","caller":"traceutil/trace.go:171","msg":"trace[1239340375] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"201.137069ms","start":"2026-05-21T11:30:03.595449Z","end":"2026-05-21T11:30:03.796586Z","steps":["trace[1239340375] 'process raft request' (duration: 200.830491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:03.796644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.324908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:30:03.796698Z","caller":"traceutil/trace.go:171","msg":"trace[960479728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25406; }","duration":"139.435822ms","start":"2026-05-21T11:30:03.657247Z","end":"2026-05-21T11:30:03.796683Z","steps":["trace[960479728] 'agreement among raft nodes before linearized reading' (duration: 139.322199ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.100174Z","caller":"traceutil/trace.go:171","msg":"trace[1389506177] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"105.368064ms","start":"2026-05-21T11:30:03.994755Z","end":"2026-05-21T11:30:04.100123Z","steps":["trace[1389506177] 'process raft request' (duration: 105.023544ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.308706Z","caller":"traceutil/trace.go:171","msg":"trace[2073750941] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"198.580329ms","start":"2026-05-21T11:30:04.110101Z","end":"2026-05-21T11:30:04.308682Z","steps":["trace[2073750941] 'process raft request' (duration: 198.394814ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.333022Z","caller":"traceutil/trace.go:171","msg":"trace[1719245028] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"209.958988ms","start":"2026-05-21T11:30:04.123039Z","end":"2026-05-21T11:30:04.332998Z","steps":["trace[1719245028] 'process raft request' (duration: 209.744082ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.333064Z","caller":"traceutil/trace.go:171","msg":"trace[735737758] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"209.195086ms","start":"2026-05-21T11:30:04.123836Z","end":"2026-05-21T11:30:04.333032Z","steps":["trace[735737758] 'process raft request' (duration: 209.071553ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.332997Z","caller":"traceutil/trace.go:171","msg":"trace[1580747472] linearizableReadLoop","detail":"{readStateIndex:26236; appliedIndex:26233; }","duration":"196.234514ms","start":"2026-05-21T11:30:04.136731Z","end":"2026-05-21T11:30:04.332966Z","steps":["trace[1580747472] 'read index received' (duration: 171.854742ms)","trace[1580747472] 'applied index is now lower than readState.Index' (duration: 24.378212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:30:04.333232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.483262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T11:30:04.333308Z","caller":"traceutil/trace.go:171","msg":"trace[765038645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25410; }","duration":"196.610155ms","start":"2026-05-21T11:30:04.136677Z","end":"2026-05-21T11:30:04.333287Z","steps":["trace[765038645] 'agreement among raft nodes before linearized reading' (duration: 196.381009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:04.33344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.422656ms","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-05-21T11:30:04.33349Z","caller":"traceutil/trace.go:171","msg":"trace[219797876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25410; }","duration":"190.491919ms","start":"2026-05-21T11:30:04.142983Z","end":"2026-05-21T11:30:04.333475Z","steps":["trace[219797876] 'agreement among raft nodes before linearized reading' (duration: 190.324364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:04.333612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.326878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-21T11:30:04.333647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.864259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-21T11:30:04.333736Z","caller":"traceutil/trace.go:171","msg":"trace[1586272113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25410; }","duration":"192.524604ms","start":"2026-05-21T11:30:04.141186Z","end":"2026-05-21T11:30:04.333711Z","steps":["trace[1586272113] 'agreement among raft nodes before linearized reading' (duration: 192.116512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:04.333742Z","caller":"traceutil/trace.go:171","msg":"trace[253247962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25410; }","duration":"190.976913ms","start":"2026-05-21T11:30:04.142744Z","end":"2026-05-21T11:30:04.333721Z","steps":["trace[253247962] 'agreement among raft nodes before linearized reading' (duration: 190.750125ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:09.515172Z","caller":"traceutil/trace.go:171","msg":"trace[1505141604] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"201.410466ms","start":"2026-05-21T11:30:09.313739Z","end":"2026-05-21T11:30:09.515149Z","steps":["trace[1505141604] 'process raft request' (duration: 201.275562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.035477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.331974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:30:10.035563Z","caller":"traceutil/trace.go:171","msg":"trace[877549042] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25451; }","duration":"471.464048ms","start":"2026-05-21T11:30:09.564075Z","end":"2026-05-21T11:30:10.035539Z","steps":["trace[877549042] 'count revisions from in-memory index tree' (duration: 471.231161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.035606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:09.564055Z","time spent":"471.540019ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T11:30:10.035902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.719963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:30:10.036135Z","caller":"traceutil/trace.go:171","msg":"trace[1630227341] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"426.871135ms","start":"2026-05-21T11:30:09.609236Z","end":"2026-05-21T11:30:10.036107Z","steps":["trace[1630227341] 'process raft request' (duration: 316.831993ms)","trace[1630227341] 'compare' (duration: 109.408155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:30:10.036323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:09.609208Z","time spent":"426.984478ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:30:10.106938Z","caller":"traceutil/trace.go:171","msg":"trace[1661696] linearizableReadLoop","detail":"{readStateIndex:26280; appliedIndex:26278; }","duration":"469.121653ms","start":"2026-05-21T11:30:09.637788Z","end":"2026-05-21T11:30:10.106909Z","steps":["trace[1661696] 'read index received' (duration: 288.38833ms)","trace[1661696] 'applied index is now lower than readState.Index' (duration: 180.732163ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:10.10701Z","caller":"traceutil/trace.go:171","msg":"trace[1113380088] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"293.941111ms","start":"2026-05-21T11:30:09.813048Z","end":"2026-05-21T11:30:10.106989Z","steps":["trace[1113380088] 'process raft request' (duration: 293.821218ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.107015Z","caller":"traceutil/trace.go:171","msg":"trace[631955821] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"296.416988ms","start":"2026-05-21T11:30:09.810559Z","end":"2026-05-21T11:30:10.106976Z","steps":["trace[631955821] 'process raft request' (duration: 296.268114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.107186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.116698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-05-21T11:30:10.107209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.39072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-21T11:30:10.107234Z","caller":"traceutil/trace.go:171","msg":"trace[1333142359] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25455; }","duration":"215.20337ms","start":"2026-05-21T11:30:09.892015Z","end":"2026-05-21T11:30:10.107218Z","steps":["trace[1333142359] 'agreement among raft nodes before linearized reading' (duration: 215.047475ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.107286Z","caller":"traceutil/trace.go:171","msg":"trace[1663156073] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25455; }","duration":"469.513484ms","start":"2026-05-21T11:30:09.637752Z","end":"2026-05-21T11:30:10.107266Z","steps":["trace[1663156073] 'agreement among raft nodes before linearized reading' (duration: 469.283988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.107321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:09.637737Z","time spent":"469.578056ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-21T11:30:10.107584Z","caller":"traceutil/trace.go:171","msg":"trace[745286259] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"483.739882ms","start":"2026-05-21T11:30:09.623824Z","end":"2026-05-21T11:30:10.107564Z","steps":["trace[745286259] 'process raft request' (duration: 482.877048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.107692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:09.623814Z","time spent":"483.812273ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:30:10.307276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.855925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:30:10.307459Z","caller":"traceutil/trace.go:171","msg":"trace[1970496955] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26282; }","duration":"183.428477ms","start":"2026-05-21T11:30:10.124004Z","end":"2026-05-21T11:30:10.307433Z","steps":["trace[1970496955] 'read index received' (duration: 19.309335ms)","trace[1970496955] 'applied index is now lower than readState.Index' (duration: 164.116502ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:10.307468Z","caller":"traceutil/trace.go:171","msg":"trace[1656086079] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"195.70931ms","start":"2026-05-21T11:30:10.111734Z","end":"2026-05-21T11:30:10.307443Z","steps":["trace[1656086079] 'process raft request' (duration: 31.595198ms)","trace[1656086079] 'compare' (duration: 163.65556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:30:10.307727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.712114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-21T11:30:10.307779Z","caller":"traceutil/trace.go:171","msg":"trace[1877609872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25456; }","duration":"183.765106ms","start":"2026-05-21T11:30:10.123999Z","end":"2026-05-21T11:30:10.307764Z","steps":["trace[1877609872] 'agreement among raft nodes before linearized reading' (duration: 183.55596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.556031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.525067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:30:10.556167Z","caller":"traceutil/trace.go:171","msg":"trace[1293661050] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"245.229698ms","start":"2026-05-21T11:30:10.310912Z","end":"2026-05-21T11:30:10.556142Z","steps":["trace[1293661050] 'process raft request' (duration: 110.495325ms)","trace[1293661050] 'compare' (duration: 134.340552ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:10.587646Z","caller":"traceutil/trace.go:171","msg":"trace[1987156574] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"275.380286ms","start":"2026-05-21T11:30:10.312237Z","end":"2026-05-21T11:30:10.587617Z","steps":["trace[1987156574] 'process raft request' (duration: 275.14269ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.587669Z","caller":"traceutil/trace.go:171","msg":"trace[1084158939] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"238.71638ms","start":"2026-05-21T11:30:10.348922Z","end":"2026-05-21T11:30:10.587639Z","steps":["trace[1084158939] 'process raft request' (duration: 238.616747ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.587642Z","caller":"traceutil/trace.go:171","msg":"trace[681104386] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26284; }","duration":"131.410482ms","start":"2026-05-21T11:30:10.456209Z","end":"2026-05-21T11:30:10.587619Z","steps":["trace[681104386] 'read index received' (duration: 131.155426ms)","trace[681104386] 'applied index is now lower than readState.Index' (duration: 254.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T11:30:10.587702Z","caller":"traceutil/trace.go:171","msg":"trace[408900534] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"235.118732ms","start":"2026-05-21T11:30:10.352552Z","end":"2026-05-21T11:30:10.587671Z","steps":["trace[408900534] 'process raft request' (duration: 235.028499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.587861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.639719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-21T11:30:10.587926Z","caller":"traceutil/trace.go:171","msg":"trace[458663651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25460; }","duration":"131.759032ms","start":"2026-05-21T11:30:10.456152Z","end":"2026-05-21T11:30:10.587911Z","steps":["trace[458663651] 'agreement among raft nodes before linearized reading' (duration: 131.537806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.587987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.273152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-21T11:30:10.587994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.014366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-21T11:30:10.588033Z","caller":"traceutil/trace.go:171","msg":"trace[1774253782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25460; }","duration":"123.371424ms","start":"2026-05-21T11:30:10.464648Z","end":"2026-05-21T11:30:10.58802Z","steps":["trace[1774253782] 'agreement among raft nodes before linearized reading' (duration: 123.21996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.588016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.826849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T11:30:10.588046Z","caller":"traceutil/trace.go:171","msg":"trace[131370659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25460; }","duration":"126.134209ms","start":"2026-05-21T11:30:10.461898Z","end":"2026-05-21T11:30:10.588032Z","steps":["trace[131370659] 'agreement among raft nodes before linearized reading' (duration: 125.971395ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.588082Z","caller":"traceutil/trace.go:171","msg":"trace[1837295899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25460; }","duration":"122.923732ms","start":"2026-05-21T11:30:10.465139Z","end":"2026-05-21T11:30:10.588062Z","steps":["trace[1837295899] 'agreement among raft nodes before linearized reading' (duration: 122.726857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.971624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.420208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T11:30:10.971744Z","caller":"traceutil/trace.go:171","msg":"trace[1482944270] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"380.106063ms","start":"2026-05-21T11:30:10.591619Z","end":"2026-05-21T11:30:10.971725Z","steps":["trace[1482944270] 'process raft request' (duration: 211.50644ms)","trace[1482944270] 'compare' (duration: 168.252754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:30:10.971804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.591603Z","time spent":"380.171186ms","remote":"127.0.0.1:48598","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-05-21T11:30:10.973576Z","caller":"traceutil/trace.go:171","msg":"trace[439588890] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"381.386828ms","start":"2026-05-21T11:30:10.592122Z","end":"2026-05-21T11:30:10.973509Z","steps":["trace[439588890] 'process raft request' (duration: 381.188313ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.973582Z","caller":"traceutil/trace.go:171","msg":"trace[1528396081] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"381.250974ms","start":"2026-05-21T11:30:10.592289Z","end":"2026-05-21T11:30:10.97354Z","steps":["trace[1528396081] 'process raft request' (duration: 381.125311ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.9736Z","caller":"traceutil/trace.go:171","msg":"trace[691042841] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"353.290715ms","start":"2026-05-21T11:30:10.62028Z","end":"2026-05-21T11:30:10.973571Z","steps":["trace[691042841] 'process raft request' (duration: 353.206853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T11:30:10.973687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.173294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-21T11:30:10.973724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.592101Z","time spent":"381.546722ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:30:10.973737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.592275Z","time spent":"381.384458ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:30:10.97374Z","caller":"traceutil/trace.go:171","msg":"trace[1406323633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25466; }","duration":"150.272696ms","start":"2026-05-21T11:30:10.823458Z","end":"2026-05-21T11:30:10.97373Z","steps":["trace[1406323633] 'agreement among raft nodes before linearized reading' (duration: 150.163643ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.973788Z","caller":"traceutil/trace.go:171","msg":"trace[453805838] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"381.474281ms","start":"2026-05-21T11:30:10.592304Z","end":"2026-05-21T11:30:10.973778Z","steps":["trace[453805838] 'process raft request' (duration: 381.150302ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.973806Z","caller":"traceutil/trace.go:171","msg":"trace[468077592] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"343.327444ms","start":"2026-05-21T11:30:10.630444Z","end":"2026-05-21T11:30:10.973771Z","steps":["trace[468077592] 'process raft request' (duration: 343.081628ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:30:10.973574Z","caller":"traceutil/trace.go:171","msg":"trace[2032050713] linearizableReadLoop","detail":"{readStateIndex:26293; appliedIndex:26288; }","duration":"150.04788ms","start":"2026-05-21T11:30:10.823508Z","end":"2026-05-21T11:30:10.973556Z","steps":["trace[2032050713] 'read index received' (duration: 149.783182ms)","trace[2032050713] 'applied index is now lower than readState.Index' (duration: 263.878µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T11:30:10.973853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.592283Z","time spent":"381.542003ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:30:10.973742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.620256Z","time spent":"353.411638ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T11:30:10.973898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T11:30:10.630429Z","time spent":"343.422766ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T11:31:19.414873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23214} {"level":"info","ts":"2026-05-21T11:31:19.542797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23214,"took":"123.772326ms","hash":1172609722,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":36163584,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-21T11:31:19.542863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1172609722,"revision":23214,"compact-revision":20198} {"level":"info","ts":"2026-05-21T11:31:41.368582Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a5c.wal"} {"level":"warn","ts":"2026-05-21T11:33:24.93626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.612691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T11:33:24.936353Z","caller":"traceutil/trace.go:171","msg":"trace[832172816] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:27408; }","duration":"106.756115ms","start":"2026-05-21T11:33:24.829577Z","end":"2026-05-21T11:33:24.936334Z","steps":["trace[832172816] 'agreement among raft nodes before linearized reading' (duration: 106.622512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:36:19.42121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26185} {"level":"info","ts":"2026-05-21T11:36:19.56558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26185,"took":"140.315293ms","hash":3872689739,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":34525184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-21T11:36:19.565646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3872689739,"revision":26185,"compact-revision":23214} {"level":"info","ts":"2026-05-21T11:36:50.065232Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T11:36:50.070671Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-21T11:36:50.070755Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-21T11:41:19.428006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28851} {"level":"info","ts":"2026-05-21T11:41:19.561629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28851,"took":"129.219646ms","hash":3832160438,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T11:41:19.561751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3832160438,"revision":28851,"compact-revision":26185} {"level":"info","ts":"2026-05-21T11:46:19.436585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31072} {"level":"info","ts":"2026-05-21T11:46:19.563531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31072,"took":"123.053091ms","hash":3053571665,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":30302208,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T11:46:19.563617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3053571665,"revision":31072,"compact-revision":28851} {"level":"info","ts":"2026-05-21T11:49:32.552657Z","caller":"traceutil/trace.go:171","msg":"trace[936974674] transaction","detail":"{read_only:false; response_revision:34810; number_of_response:1; }","duration":"115.989498ms","start":"2026-05-21T11:49:32.436636Z","end":"2026-05-21T11:49:32.552626Z","steps":["trace[936974674] 'process raft request' (duration: 115.737701ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:49:32.555118Z","caller":"traceutil/trace.go:171","msg":"trace[1963030436] transaction","detail":"{read_only:false; response_revision:34812; number_of_response:1; }","duration":"115.298039ms","start":"2026-05-21T11:49:32.439783Z","end":"2026-05-21T11:49:32.555081Z","steps":["trace[1963030436] 'process raft request' (duration: 115.179975ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:49:32.555266Z","caller":"traceutil/trace.go:171","msg":"trace[380093175] transaction","detail":"{read_only:false; response_revision:34813; number_of_response:1; }","duration":"115.067332ms","start":"2026-05-21T11:49:32.440176Z","end":"2026-05-21T11:49:32.555244Z","steps":["trace[380093175] 'process raft request' (duration: 114.834576ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:49:32.555139Z","caller":"traceutil/trace.go:171","msg":"trace[175326566] transaction","detail":"{read_only:false; response_revision:34811; number_of_response:1; }","duration":"115.932646ms","start":"2026-05-21T11:49:32.439172Z","end":"2026-05-21T11:49:32.555105Z","steps":["trace[175326566] 'process raft request' (duration: 115.578807ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T11:51:19.450962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33335} {"level":"info","ts":"2026-05-21T11:51:19.610082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33335,"took":"152.896543ms","hash":40299303,"current-db-size-bytes":61050880,"current-db-size":"61 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T11:51:19.610153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":40299303,"revision":33335,"compact-revision":31072} {"level":"info","ts":"2026-05-21T11:56:19.464157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35630} {"level":"info","ts":"2026-05-21T11:56:19.614168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35630,"took":"145.1967ms","hash":2409463896,"current-db-size-bytes":61747200,"current-db-size":"62 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-21T11:56:19.614247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2409463896,"revision":35630,"compact-revision":33335} {"level":"info","ts":"2026-05-21T11:58:21.831205Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T11:58:21.836243Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-21T11:58:21.836333Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-21T12:01:19.470411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37858} {"level":"info","ts":"2026-05-21T12:01:19.602788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37858,"took":"120.42788ms","hash":950458979,"current-db-size-bytes":61747200,"current-db-size":"62 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T12:01:19.602858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":950458979,"revision":37858,"compact-revision":35630}