{"level":"warn","ts":"2026-05-21T19:32:58.939389Z","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-21T19:32:58.939491Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18: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-21T19:32:58.93957Z","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-21T19:32:58.939596Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-05-21T19:32:58.939626Z","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-21T19:32:58.940315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-05-21T19:32:58.94039Z","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.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18: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-21T19:32:58.943968Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.354526ms"} {"level":"info","ts":"2026-05-21T19:32:58.955822Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-05-21T19:32:58.955898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:32:58.955922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-05-21T19:32:58.955936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:32:58.955954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-05-21T19:32:58.955993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-05-21T19:32:58.960476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:32:58.961869Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:32:58.962969Z","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-21T19:32:58.965084Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:32:58.965226Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:32:58.965229Z","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-21T19:32:58.965379Z","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-21T19:32:58.965392Z","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-21T19:32:58.967302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-05-21T19:32:58.969306Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-05-21T19:32:58.971651Z","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-21T19:32:58.97183Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-05-21T19:32:58.971885Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-05-21T19:32:58.972296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:32:58.972379Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:32:59.756224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:32:59.756313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:32:59.756385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-05-21T19:32:59.756411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:32:59.756423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-05-21T19:32:59.75645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-05-21T19:32:59.756472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-05-21T19:32:59.758165Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:32:59.758419Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:32:59.758462Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:32:59.758732Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:59.758933Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:32:59.759032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:32:59.760004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:59.760164Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:59.760214Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:59.76213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:32:59.762395Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-05-21T19:33:18.03606Z","caller":"traceutil/trace.go:171","msg":"trace[1800969315] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"113.231009ms","start":"2026-05-21T19:33:17.922801Z","end":"2026-05-21T19:33:18.036032Z","steps":["trace[1800969315] 'read index received' (duration: 113.059725ms)","trace[1800969315] 'applied index is now lower than readState.Index' (duration: 169.864µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:18.036175Z","caller":"traceutil/trace.go:171","msg":"trace[516796261] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"217.235653ms","start":"2026-05-21T19:33:17.818917Z","end":"2026-05-21T19:33:18.036153Z","steps":["trace[516796261] 'process raft request' (duration: 216.925396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:18.036224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.398553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:18.036292Z","caller":"traceutil/trace.go:171","msg":"trace[68054553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"113.534138ms","start":"2026-05-21T19:33:17.922747Z","end":"2026-05-21T19:33:18.036281Z","steps":["trace[68054553] 'agreement among raft nodes before linearized reading' (duration: 113.417425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:18.87265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.64803ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:18.87283Z","caller":"traceutil/trace.go:171","msg":"trace[1803751457] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:377; }","duration":"347.857986ms","start":"2026-05-21T19:33:18.52495Z","end":"2026-05-21T19:33:18.872808Z","steps":["trace[1803751457] 'range keys from in-memory index tree' (duration: 347.618739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:18.874407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.120623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:33:18.874527Z","caller":"traceutil/trace.go:171","msg":"trace[582104658] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"836.722449ms","start":"2026-05-21T19:33:18.037786Z","end":"2026-05-21T19:33:18.874509Z","steps":["trace[582104658] 'read index received' (duration: 419.307739ms)","trace[582104658] 'applied index is now lower than readState.Index' (duration: 417.41321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:18.874617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.816071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:18.87468Z","caller":"traceutil/trace.go:171","msg":"trace[971673563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"836.883453ms","start":"2026-05-21T19:33:18.037782Z","end":"2026-05-21T19:33:18.874666Z","steps":["trace[971673563] 'agreement among raft nodes before linearized reading' (duration: 836.77111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:18.874739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:18.037688Z","time spent":"837.038416ms","remote":"127.0.0.1:53336","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-21T19:33:25.478318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540166046,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:33:25.701386Z","caller":"traceutil/trace.go:171","msg":"trace[278125476] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"779.243953ms","start":"2026-05-21T19:33:24.922108Z","end":"2026-05-21T19:33:25.701352Z","steps":["trace[278125476] 'process raft request' (duration: 779.032728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:25.702596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:24.922071Z","time spent":"779.428367ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:25.703623Z","caller":"traceutil/trace.go:171","msg":"trace[2036010020] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"725.987893ms","start":"2026-05-21T19:33:24.9776Z","end":"2026-05-21T19:33:25.703588Z","steps":["trace[2036010020] 'read index received' (duration: 723.51368ms)","trace[2036010020] 'applied index is now lower than readState.Index' (duration: 2.472963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:25.70399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.356342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-21T19:33:25.703974Z","caller":"traceutil/trace.go:171","msg":"trace[726256819] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"669.37591ms","start":"2026-05-21T19:33:25.034558Z","end":"2026-05-21T19:33:25.703934Z","steps":["trace[726256819] 'process raft request' (duration: 668.874777ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:25.704054Z","caller":"traceutil/trace.go:171","msg":"trace[642702322] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:394; }","duration":"726.498186ms","start":"2026-05-21T19:33:24.977539Z","end":"2026-05-21T19:33:25.704038Z","steps":["trace[642702322] 'agreement among raft nodes before linearized reading' (duration: 726.317962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:25.7041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:24.977511Z","time spent":"726.579069ms","remote":"127.0.0.1:53802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-21T19:33:25.704084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.294154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T19:33:25.70419Z","caller":"traceutil/trace.go:171","msg":"trace[745593267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"556.479909ms","start":"2026-05-21T19:33:25.147683Z","end":"2026-05-21T19:33:25.704163Z","steps":["trace[745593267] 'agreement among raft nodes before linearized reading' (duration: 556.288365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:25.704201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.034526Z","time spent":"669.553093ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:25.704239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.147654Z","time spent":"556.575582ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:33:26.276415Z","caller":"traceutil/trace.go:171","msg":"trace[7129249] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"403.213994ms","start":"2026-05-21T19:33:25.873164Z","end":"2026-05-21T19:33:26.276378Z","steps":["trace[7129249] 'process raft request' (duration: 399.642874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:26.276536Z","caller":"traceutil/trace.go:171","msg":"trace[429837429] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"402.167847ms","start":"2026-05-21T19:33:25.87436Z","end":"2026-05-21T19:33:26.276528Z","steps":["trace[429837429] 'process raft request' (duration: 401.986743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:26.276627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.873137Z","time spent":"403.404389ms","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:33:26.276662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.87434Z","time spent":"402.237619ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:26.27646Z","caller":"traceutil/trace.go:171","msg":"trace[720645013] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"403.247035ms","start":"2026-05-21T19:33:25.873185Z","end":"2026-05-21T19:33:26.276432Z","steps":["trace[720645013] 'process raft request' (duration: 403.100061ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:26.2768Z","caller":"traceutil/trace.go:171","msg":"trace[1640145154] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:418; }","duration":"353.629372ms","start":"2026-05-21T19:33:25.923147Z","end":"2026-05-21T19:33:26.276776Z","steps":["trace[1640145154] 'read index received' (duration: 39.376984ms)","trace[1640145154] 'applied index is now lower than readState.Index' (duration: 314.250008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:26.276856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.873157Z","time spent":"403.645656ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:26.27706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.915089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:26.277387Z","caller":"traceutil/trace.go:171","msg":"trace[584552235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"354.243347ms","start":"2026-05-21T19:33:25.923092Z","end":"2026-05-21T19:33:26.277336Z","steps":["trace[584552235] 'agreement among raft nodes before linearized reading' (duration: 353.92629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:26.277457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.923078Z","time spent":"354.363541ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:33:26.277035Z","caller":"traceutil/trace.go:171","msg":"trace[1042711262] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"401.85854ms","start":"2026-05-21T19:33:25.875075Z","end":"2026-05-21T19:33:26.276934Z","steps":["trace[1042711262] 'process raft request' (duration: 401.308956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:26.277474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.296946ms","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-21T19:33:26.277561Z","caller":"traceutil/trace.go:171","msg":"trace[1110688702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"186.45516ms","start":"2026-05-21T19:33:26.091083Z","end":"2026-05-21T19:33:26.277538Z","steps":["trace[1110688702] 'agreement among raft nodes before linearized reading' (duration: 186.259136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:26.277593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:25.875067Z","time spent":"402.457545ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:41.102206Z","caller":"traceutil/trace.go:171","msg":"trace[367903090] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"269.982399ms","start":"2026-05-21T19:33:40.8322Z","end":"2026-05-21T19:33:41.102182Z","steps":["trace[367903090] 'read index received' (duration: 264.936122ms)","trace[367903090] 'applied index is now lower than readState.Index' (duration: 5.045617ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:41.102453Z","caller":"traceutil/trace.go:171","msg":"trace[1289921598] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"298.557911ms","start":"2026-05-21T19:33:40.803851Z","end":"2026-05-21T19:33:41.102409Z","steps":["trace[1289921598] 'process raft request' (duration: 293.306088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.10262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.406179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-21T19:33:41.102717Z","caller":"traceutil/trace.go:171","msg":"trace[1860816399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:580; }","duration":"270.555903ms","start":"2026-05-21T19:33:40.832146Z","end":"2026-05-21T19:33:41.102702Z","steps":["trace[1860816399] 'agreement among raft nodes before linearized reading' (duration: 270.309307ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:41.102917Z","caller":"traceutil/trace.go:171","msg":"trace[1915651450] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"240.624267ms","start":"2026-05-21T19:33:40.862281Z","end":"2026-05-21T19:33:41.102905Z","steps":["trace[1915651450] 'process raft request' (duration: 240.305259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.1032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.019225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-crrmn\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-05-21T19:33:41.103263Z","caller":"traceutil/trace.go:171","msg":"trace[2121881135] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-crrmn; range_end:; response_count:1; response_revision:583; }","duration":"254.095478ms","start":"2026-05-21T19:33:40.849157Z","end":"2026-05-21T19:33:41.103252Z","steps":["trace[2121881135] 'agreement among raft nodes before linearized reading' (duration: 253.972425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.103228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.500618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:41.103276Z","caller":"traceutil/trace.go:171","msg":"trace[74774075] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"220.12527ms","start":"2026-05-21T19:33:40.883074Z","end":"2026-05-21T19:33:41.103199Z","steps":["trace[74774075] 'process raft request' (duration: 219.803512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:41.103353Z","caller":"traceutil/trace.go:171","msg":"trace[2134769565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"180.644352ms","start":"2026-05-21T19:33:40.922699Z","end":"2026-05-21T19:33:41.103343Z","steps":["trace[2134769565] 'agreement among raft nodes before linearized reading' (duration: 180.491988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:41.537177Z","caller":"traceutil/trace.go:171","msg":"trace[1242435637] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"374.895839ms","start":"2026-05-21T19:33:41.16225Z","end":"2026-05-21T19:33:41.537146Z","steps":["trace[1242435637] 'read index received' (duration: 374.771426ms)","trace[1242435637] 'applied index is now lower than readState.Index' (duration: 123.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:41.53722Z","caller":"traceutil/trace.go:171","msg":"trace[433850126] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"379.591678ms","start":"2026-05-21T19:33:41.157606Z","end":"2026-05-21T19:33:41.537197Z","steps":["trace[433850126] 'process raft request' (duration: 379.328602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.53736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:41.157597Z","time spent":"379.67757ms","remote":"127.0.0.1:53802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:41.537447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.168085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-05-21T19:33:41.537527Z","caller":"traceutil/trace.go:171","msg":"trace[1654011061] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:588; }","duration":"375.261328ms","start":"2026-05-21T19:33:41.162245Z","end":"2026-05-21T19:33:41.537507Z","steps":["trace[1654011061] 'agreement among raft nodes before linearized reading' (duration: 375.040612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.537562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:41.162191Z","time spent":"375.363771ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31168,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-21T19:33:41.687392Z","caller":"traceutil/trace.go:171","msg":"trace[384907997] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"518.793674ms","start":"2026-05-21T19:33:41.168576Z","end":"2026-05-21T19:33:41.68737Z","steps":["trace[384907997] 'process raft request' (duration: 518.581058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.687538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:41.168553Z","time spent":"518.908356ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:41.687853Z","caller":"traceutil/trace.go:171","msg":"trace[524605214] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"513.769507ms","start":"2026-05-21T19:33:41.174027Z","end":"2026-05-21T19:33:41.687797Z","steps":["trace[524605214] 'process raft request' (duration: 513.286795ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:41.688133Z","caller":"traceutil/trace.go:171","msg":"trace[1269706574] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:610; }","duration":"150.86214ms","start":"2026-05-21T19:33:41.537255Z","end":"2026-05-21T19:33:41.688118Z","steps":["trace[1269706574] 'read index received' (duration: 149.742431ms)","trace[1269706574] 'applied index is now lower than readState.Index' (duration: 1.117969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:41.688148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:41.174003Z","time spent":"513.985252ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:41.688228Z","caller":"traceutil/trace.go:171","msg":"trace[914157141] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"202.841693ms","start":"2026-05-21T19:33:41.48537Z","end":"2026-05-21T19:33:41.688212Z","steps":["trace[914157141] 'process raft request' (duration: 202.284479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.68856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.00677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:41.688597Z","caller":"traceutil/trace.go:171","msg":"trace[854697648] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:591; }","duration":"407.125662ms","start":"2026-05-21T19:33:41.28146Z","end":"2026-05-21T19:33:41.688586Z","steps":["trace[854697648] 'agreement among raft nodes before linearized reading' (duration: 407.04237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:41.688648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:41.281431Z","time spent":"407.186073ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-21T19:33:41.688683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.260297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-21T19:33:41.688765Z","caller":"traceutil/trace.go:171","msg":"trace[1229240761] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"139.37946ms","start":"2026-05-21T19:33:41.549364Z","end":"2026-05-21T19:33:41.688743Z","steps":["trace[1229240761] 'agreement among raft nodes before linearized reading' (duration: 139.094223ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:47.464556Z","caller":"traceutil/trace.go:171","msg":"trace[438497965] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"500.999823ms","start":"2026-05-21T19:33:46.963524Z","end":"2026-05-21T19:33:47.464524Z","steps":["trace[438497965] 'process raft request' (duration: 500.350417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:47.46475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:46.963496Z","time spent":"501.162008ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:48.183071Z","caller":"traceutil/trace.go:171","msg":"trace[1311545046] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"362.131286ms","start":"2026-05-21T19:33:47.82091Z","end":"2026-05-21T19:33:48.183041Z","steps":["trace[1311545046] 'read index received' (duration: 362.010863ms)","trace[1311545046] 'applied index is now lower than readState.Index' (duration: 119.253µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:48.183195Z","caller":"traceutil/trace.go:171","msg":"trace[161742020] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"365.731857ms","start":"2026-05-21T19:33:47.817453Z","end":"2026-05-21T19:33:48.183184Z","steps":["trace[161742020] 'process raft request' (duration: 365.435529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.183305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.817436Z","time spent":"365.786869ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:48.183605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.67085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:33:48.18369Z","caller":"traceutil/trace.go:171","msg":"trace[2026065386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"362.814275ms","start":"2026-05-21T19:33:47.820853Z","end":"2026-05-21T19:33:48.183667Z","steps":["trace[2026065386] 'agreement among raft nodes before linearized reading' (duration: 362.439434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.183741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.820828Z","time spent":"362.903916ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:33:48.435772Z","caller":"traceutil/trace.go:171","msg":"trace[1678226709] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"614.689866ms","start":"2026-05-21T19:33:47.821037Z","end":"2026-05-21T19:33:48.435727Z","steps":["trace[1678226709] 'process raft request' (duration: 544.051331ms)","trace[1678226709] 'compare' (duration: 70.352917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:48.435953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.821006Z","time spent":"614.86049ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:48.557948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.300879ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:33:48.558246Z","caller":"traceutil/trace.go:171","msg":"trace[1704361763] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"369.909893ms","start":"2026-05-21T19:33:48.188313Z","end":"2026-05-21T19:33:48.558223Z","steps":["trace[1704361763] 'process raft request' (duration: 369.775799ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:48.55824Z","caller":"traceutil/trace.go:171","msg":"trace[1817194953] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"375.061292ms","start":"2026-05-21T19:33:48.183145Z","end":"2026-05-21T19:33:48.558206Z","steps":["trace[1817194953] 'read index received' (duration: 181.984206ms)","trace[1817194953] 'applied index is now lower than readState.Index' (duration: 193.075586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:48.55837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.188286Z","time spent":"370.005894ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:48.558424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.825979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:48.558469Z","caller":"traceutil/trace.go:171","msg":"trace[1984217248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"635.945371ms","start":"2026-05-21T19:33:47.922511Z","end":"2026-05-21T19:33:48.558457Z","steps":["trace[1984217248] 'agreement among raft nodes before linearized reading' (duration: 635.848729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.558512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.922487Z","time spent":"636.018054ms","remote":"127.0.0.1:53330","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-21T19:33:48.558578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.225247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:33:48.558687Z","caller":"traceutil/trace.go:171","msg":"trace[1872632524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:658; }","duration":"242.381401ms","start":"2026-05-21T19:33:48.316281Z","end":"2026-05-21T19:33:48.558663Z","steps":["trace[1872632524] 'agreement among raft nodes before linearized reading' (duration: 242.137424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.558721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.124215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nrhvz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-21T19:33:48.558776Z","caller":"traceutil/trace.go:171","msg":"trace[852783659] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nrhvz; range_end:; response_count:1; response_revision:658; }","duration":"689.234718ms","start":"2026-05-21T19:33:47.869523Z","end":"2026-05-21T19:33:48.558758Z","steps":["trace[852783659] 'agreement among raft nodes before linearized reading' (duration: 689.019253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.55883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.869497Z","time spent":"689.320419ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-nrhvz\" "} {"level":"warn","ts":"2026-05-21T19:33:48.558897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.06855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:33:48.558952Z","caller":"traceutil/trace.go:171","msg":"trace[605768661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"173.209093ms","start":"2026-05-21T19:33:48.38573Z","end":"2026-05-21T19:33:48.55894Z","steps":["trace[605768661] 'agreement among raft nodes before linearized reading' (duration: 172.912816ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:49.316528Z","caller":"traceutil/trace.go:171","msg":"trace[1986713573] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"753.895921ms","start":"2026-05-21T19:33:48.562607Z","end":"2026-05-21T19:33:49.316503Z","steps":["trace[1986713573] 'process raft request' (duration: 753.758638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:49.316679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.562588Z","time spent":"754.016085ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:49.378618Z","caller":"traceutil/trace.go:171","msg":"trace[1673549061] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"805.078604ms","start":"2026-05-21T19:33:48.573024Z","end":"2026-05-21T19:33:49.378103Z","steps":["trace[1673549061] 'process raft request' (duration: 804.873759ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:49.378707Z","caller":"traceutil/trace.go:171","msg":"trace[42697906] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"815.484496ms","start":"2026-05-21T19:33:48.5632Z","end":"2026-05-21T19:33:49.378685Z","steps":["trace[42697906] 'process raft request' (duration: 814.523312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:49.378763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.31727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:33:49.37882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.573005Z","time spent":"805.715019ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:49.378861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.563184Z","time spent":"815.588369ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:49.378091Z","caller":"traceutil/trace.go:171","msg":"trace[910768294] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:682; }","duration":"455.240318ms","start":"2026-05-21T19:33:48.922824Z","end":"2026-05-21T19:33:49.378065Z","steps":["trace[910768294] 'read index received' (duration: 393.617061ms)","trace[910768294] 'applied index is now lower than readState.Index' (duration: 61.622017ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:49.378913Z","caller":"traceutil/trace.go:171","msg":"trace[1268395917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"456.09512ms","start":"2026-05-21T19:33:48.922771Z","end":"2026-05-21T19:33:49.378866Z","steps":["trace[1268395917] 'agreement among raft nodes before linearized reading' (duration: 455.338681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:49.381768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.922744Z","time spent":"458.91721ms","remote":"127.0.0.1:53330","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-21T19:33:51.583214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660732163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:33:51.583282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395118333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:33:51.583304Z","caller":"traceutil/trace.go:171","msg":"trace[235480227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.660858517s","start":"2026-05-21T19:33:49.922423Z","end":"2026-05-21T19:33:51.583281Z","steps":["trace[235480227] 'range keys from in-memory index tree' (duration: 1.660650031s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:51.583336Z","caller":"traceutil/trace.go:171","msg":"trace[1269898875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.395218766s","start":"2026-05-21T19:33:50.188102Z","end":"2026-05-21T19:33:51.583321Z","steps":["trace[1269898875] 'range keys from in-memory index tree' (duration: 1.394896578s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:51.583358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.922398Z","time spent":"1.660947569s","remote":"127.0.0.1:53336","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-21T19:33:51.583368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:50.188079Z","time spent":"1.395281638s","remote":"127.0.0.1:53632","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-21T19:33:51.583639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14273397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:33:51.583733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.029902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-21T19:33:51.583717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.760467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:33:51.583758Z","caller":"traceutil/trace.go:171","msg":"trace[1417699966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.142892374s","start":"2026-05-21T19:33:50.440837Z","end":"2026-05-21T19:33:51.583729Z","steps":["trace[1417699966] 'range keys from in-memory index tree' (duration: 1.142470723s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:51.583787Z","caller":"traceutil/trace.go:171","msg":"trace[48131270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"198.117133ms","start":"2026-05-21T19:33:51.385654Z","end":"2026-05-21T19:33:51.583772Z","steps":["trace[48131270] 'range keys from in-memory index tree' (duration: 197.924529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:51.583824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:50.440811Z","time spent":"1.142999447s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:33:51.583821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021875617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:33:51.583826Z","caller":"traceutil/trace.go:171","msg":"trace[412787223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"262.90579ms","start":"2026-05-21T19:33:51.320893Z","end":"2026-05-21T19:33:51.583799Z","steps":["trace[412787223] 'range keys from in-memory index tree' (duration: 262.591763ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:51.583886Z","caller":"traceutil/trace.go:171","msg":"trace[1968140804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.02196959s","start":"2026-05-21T19:33:50.561899Z","end":"2026-05-21T19:33:51.583869Z","steps":["trace[1968140804] 'range keys from in-memory index tree' (duration: 1.021681502s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:51.583942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:50.561877Z","time spent":"1.022052532s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:34:07.42744Z","caller":"traceutil/trace.go:171","msg":"trace[446690290] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"116.504193ms","start":"2026-05-21T19:34:07.310904Z","end":"2026-05-21T19:34:07.427408Z","steps":["trace[446690290] 'process raft request' (duration: 103.541125ms)","trace[446690290] 'compare' (duration: 12.819115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:08.187276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540166776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:08.687787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540166776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:08.87668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198771846s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:34:08.87718Z","caller":"traceutil/trace.go:171","msg":"trace[532137588] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.199370271s","start":"2026-05-21T19:34:07.67778Z","end":"2026-05-21T19:34:08.87715Z","steps":["trace[532137588] 'process raft request' (duration: 1.199181397s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.877335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.677759Z","time spent":"1.199479815s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:09.135337Z","caller":"traceutil/trace.go:171","msg":"trace[1693021049] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.454068755s","start":"2026-05-21T19:34:07.68124Z","end":"2026-05-21T19:34:09.135309Z","steps":["trace[1693021049] 'process raft request' (duration: 1.440051241s)","trace[1693021049] 'compare' (duration: 13.85665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:09.135497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.681203Z","time spent":"1.454215339s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:09.142169Z","caller":"traceutil/trace.go:171","msg":"trace[1970454019] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:805; }","duration":"1.455984974s","start":"2026-05-21T19:34:07.686162Z","end":"2026-05-21T19:34:09.142147Z","steps":["trace[1970454019] 'read index received' (duration: 1.190858067s)","trace[1970454019] 'applied index is now lower than readState.Index' (duration: 265.125417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:09.14242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456246741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:09.142456Z","caller":"traceutil/trace.go:171","msg":"trace[1763818836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.456329413s","start":"2026-05-21T19:34:07.686114Z","end":"2026-05-21T19:34:09.142444Z","steps":["trace[1763818836] 'agreement among raft nodes before linearized reading' (duration: 1.456189529s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.142488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.686096Z","time spent":"1.456383735s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:09.142525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453799399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:34:09.142614Z","caller":"traceutil/trace.go:171","msg":"trace[352356473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.453928432s","start":"2026-05-21T19:34:07.688667Z","end":"2026-05-21T19:34:09.142596Z","steps":["trace[352356473] 'agreement among raft nodes before linearized reading' (duration: 1.453678185s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.142665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.688651Z","time spent":"1.454005054s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:09.142846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158522861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qvcl6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-21T19:34:09.142888Z","caller":"traceutil/trace.go:171","msg":"trace[1122121187] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qvcl6; range_end:; response_count:1; response_revision:778; }","duration":"1.158606403s","start":"2026-05-21T19:34:07.984271Z","end":"2026-05-21T19:34:09.142877Z","steps":["trace[1122121187] 'agreement among raft nodes before linearized reading' (duration: 1.158472229s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.142902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219633514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:09.142944Z","caller":"traceutil/trace.go:171","msg":"trace[1179161733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"1.219673735s","start":"2026-05-21T19:34:07.92326Z","end":"2026-05-21T19:34:09.142934Z","steps":["trace[1179161733] 'agreement among raft nodes before linearized reading' (duration: 1.219616324s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.142957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.158099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:34:09.142978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.923199Z","time spent":"1.219773537s","remote":"127.0.0.1:53330","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-21T19:34:09.14302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.031881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:09.143084Z","caller":"traceutil/trace.go:171","msg":"trace[839414209] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:778; }","duration":"972.135704ms","start":"2026-05-21T19:34:08.170942Z","end":"2026-05-21T19:34:09.143078Z","steps":["trace[839414209] 'agreement among raft nodes before linearized reading' (duration: 972.057072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.143118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.170912Z","time spent":"972.198034ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:09.142915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.984248Z","time spent":"1.158659543s","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qvcl6\" "} {"level":"info","ts":"2026-05-21T19:34:09.142984Z","caller":"traceutil/trace.go:171","msg":"trace[1897064650] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:778; }","duration":"583.2148ms","start":"2026-05-21T19:34:08.559762Z","end":"2026-05-21T19:34:09.142977Z","steps":["trace[1897064650] 'agreement among raft nodes before linearized reading' (duration: 583.168529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.143235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.559741Z","time spent":"583.470867ms","remote":"127.0.0.1:53534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:09.143446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454499136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:34:09.143483Z","caller":"traceutil/trace.go:171","msg":"trace[1431865042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.454566917s","start":"2026-05-21T19:34:07.688907Z","end":"2026-05-21T19:34:09.143474Z","steps":["trace[1431865042] 'agreement among raft nodes before linearized reading' (duration: 1.454436194s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.143507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.68889Z","time spent":"1.45461105s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:34:18.226591Z","caller":"traceutil/trace.go:171","msg":"trace[1033020800] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"110.988103ms","start":"2026-05-21T19:34:18.115561Z","end":"2026-05-21T19:34:18.226549Z","steps":["trace[1033020800] 'process raft request' (duration: 105.757171ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:21.219358Z","caller":"traceutil/trace.go:171","msg":"trace[596825623] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"138.891637ms","start":"2026-05-21T19:34:21.08044Z","end":"2026-05-21T19:34:21.219332Z","steps":["trace[596825623] 'process raft request' (duration: 138.159729ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.07593Z","caller":"traceutil/trace.go:171","msg":"trace[922333610] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"115.315832ms","start":"2026-05-21T19:34:25.96058Z","end":"2026-05-21T19:34:26.075896Z","steps":["trace[922333610] 'process raft request' (duration: 114.939423ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.25016Z","caller":"traceutil/trace.go:171","msg":"trace[369687495] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"154.727868ms","start":"2026-05-21T19:34:26.095395Z","end":"2026-05-21T19:34:26.250123Z","steps":["trace[369687495] 'process raft request' (duration: 154.654466ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.250208Z","caller":"traceutil/trace.go:171","msg":"trace[133310711] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"155.718834ms","start":"2026-05-21T19:34:26.094447Z","end":"2026-05-21T19:34:26.250166Z","steps":["trace[133310711] 'process raft request' (duration: 106.385877ms)","trace[133310711] 'compare' (duration: 48.998868ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:26.433621Z","caller":"traceutil/trace.go:171","msg":"trace[1626814260] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"131.316886ms","start":"2026-05-21T19:34:26.302268Z","end":"2026-05-21T19:34:26.433585Z","steps":["trace[1626814260] 'process raft request' (duration: 112.918712ms)","trace[1626814260] 'compare' (duration: 17.983274ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:26.475899Z","caller":"traceutil/trace.go:171","msg":"trace[2114449853] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"172.965978ms","start":"2026-05-21T19:34:26.302901Z","end":"2026-05-21T19:34:26.475867Z","steps":["trace[2114449853] 'process raft request' (duration: 172.587468ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.47593Z","caller":"traceutil/trace.go:171","msg":"trace[694437135] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"171.283447ms","start":"2026-05-21T19:34:26.304627Z","end":"2026-05-21T19:34:26.475911Z","steps":["trace[694437135] 'process raft request' (duration: 171.057141ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.47605Z","caller":"traceutil/trace.go:171","msg":"trace[871096107] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"160.419353ms","start":"2026-05-21T19:34:26.315618Z","end":"2026-05-21T19:34:26.476038Z","steps":["trace[871096107] 'process raft request' (duration: 160.29507ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.476044Z","caller":"traceutil/trace.go:171","msg":"trace[1083949467] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"159.672903ms","start":"2026-05-21T19:34:26.316341Z","end":"2026-05-21T19:34:26.476014Z","steps":["trace[1083949467] 'process raft request' (duration: 159.628342ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:26.47626Z","caller":"traceutil/trace.go:171","msg":"trace[1793561717] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"163.687935ms","start":"2026-05-21T19:34:26.312321Z","end":"2026-05-21T19:34:26.476009Z","steps":["trace[1793561717] 'process raft request' (duration: 163.48787ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.217173Z","caller":"traceutil/trace.go:171","msg":"trace[1485656942] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"114.31414ms","start":"2026-05-21T19:34:31.102829Z","end":"2026-05-21T19:34:31.217143Z","steps":["trace[1485656942] 'process raft request' (duration: 114.114365ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.507627Z","caller":"traceutil/trace.go:171","msg":"trace[1343741944] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"103.016229ms","start":"2026-05-21T19:34:31.404583Z","end":"2026-05-21T19:34:31.5076Z","steps":["trace[1343741944] 'read index received' (duration: 61.126654ms)","trace[1343741944] 'applied index is now lower than readState.Index' (duration: 41.888445ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:31.507673Z","caller":"traceutil/trace.go:171","msg":"trace[1853466312] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"107.323987ms","start":"2026-05-21T19:34:31.400319Z","end":"2026-05-21T19:34:31.507643Z","steps":["trace[1853466312] 'process raft request' (duration: 65.449452ms)","trace[1853466312] 'compare' (duration: 41.718791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:31.507898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.288375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:34:31.50794Z","caller":"traceutil/trace.go:171","msg":"trace[422997996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1130; }","duration":"103.364028ms","start":"2026-05-21T19:34:31.404565Z","end":"2026-05-21T19:34:31.507929Z","steps":["trace[422997996] 'agreement among raft nodes before linearized reading' (duration: 103.202264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.507909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.8743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:31.508009Z","caller":"traceutil/trace.go:171","msg":"trace[839017993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1130; }","duration":"101.998644ms","start":"2026-05-21T19:34:31.405986Z","end":"2026-05-21T19:34:31.507985Z","steps":["trace[839017993] 'agreement among raft nodes before linearized reading' (duration: 101.771998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.769184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540167567,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:34:39.93419Z","caller":"traceutil/trace.go:171","msg":"trace[134191649] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"665.76623ms","start":"2026-05-21T19:34:39.26839Z","end":"2026-05-21T19:34:39.934156Z","steps":["trace[134191649] 'read index received' (duration: 665.547354ms)","trace[134191649] 'applied index is now lower than readState.Index' (duration: 217.296µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:39.934261Z","caller":"traceutil/trace.go:171","msg":"trace[2053639529] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"674.776704ms","start":"2026-05-21T19:34:39.259447Z","end":"2026-05-21T19:34:39.934223Z","steps":["trace[2053639529] 'process raft request' (duration: 674.507888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.934382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.961015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:39.934481Z","caller":"traceutil/trace.go:171","msg":"trace[473221110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"666.134919ms","start":"2026-05-21T19:34:39.268323Z","end":"2026-05-21T19:34:39.934458Z","steps":["trace[473221110] 'agreement among raft nodes before linearized reading' (duration: 665.939634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.934512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.046102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T19:34:39.934547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.268294Z","time spent":"666.244462ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:34:39.934558Z","caller":"traceutil/trace.go:171","msg":"trace[1963801600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"519.153234ms","start":"2026-05-21T19:34:39.415391Z","end":"2026-05-21T19:34:39.934544Z","steps":["trace[1963801600] 'agreement among raft nodes before linearized reading' (duration: 519.008551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.934601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.415361Z","time spent":"519.231986ms","remote":"127.0.0.1:53632","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-21T19:34:39.934652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.540615ms","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-21T19:34:39.934677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.076298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:39.93472Z","caller":"traceutil/trace.go:171","msg":"trace[714938740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"363.629567ms","start":"2026-05-21T19:34:39.571072Z","end":"2026-05-21T19:34:39.934702Z","steps":["trace[714938740] 'agreement among raft nodes before linearized reading' (duration: 363.35113ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:39.934765Z","caller":"traceutil/trace.go:171","msg":"trace[1148373291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1177; }","duration":"364.196471ms","start":"2026-05-21T19:34:39.570546Z","end":"2026-05-21T19:34:39.934743Z","steps":["trace[1148373291] 'agreement among raft nodes before linearized reading' (duration: 363.968685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.934768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.57099Z","time spent":"363.76927ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:39.934807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.570534Z","time spent":"364.265182ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:39.934801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.05769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:34:39.93486Z","caller":"traceutil/trace.go:171","msg":"trace[1387766895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1177; }","duration":"393.122281ms","start":"2026-05-21T19:34:39.541722Z","end":"2026-05-21T19:34:39.934844Z","steps":["trace[1387766895] 'agreement among raft nodes before linearized reading' (duration: 392.820394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.934907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.541657Z","time spent":"393.242165ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:39.935034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.259415Z","time spent":"674.945609ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:40.227762Z","caller":"traceutil/trace.go:171","msg":"trace[1595202041] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"287.574361ms","start":"2026-05-21T19:34:39.940165Z","end":"2026-05-21T19:34:40.227739Z","steps":["trace[1595202041] 'process raft request' (duration: 285.450568ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.228065Z","caller":"traceutil/trace.go:171","msg":"trace[531851565] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"287.812156ms","start":"2026-05-21T19:34:39.940238Z","end":"2026-05-21T19:34:40.22805Z","steps":["trace[531851565] 'process raft request' (duration: 287.447347ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.228375Z","caller":"traceutil/trace.go:171","msg":"trace[1616365934] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"288.082473ms","start":"2026-05-21T19:34:39.94028Z","end":"2026-05-21T19:34:40.228362Z","steps":["trace[1616365934] 'process raft request' (duration: 287.705314ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.228521Z","caller":"traceutil/trace.go:171","msg":"trace[429146264] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"287.039117ms","start":"2026-05-21T19:34:39.941442Z","end":"2026-05-21T19:34:40.228482Z","steps":["trace[429146264] 'process raft request' (duration: 286.818962ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:03.043232Z","caller":"traceutil/trace.go:171","msg":"trace[1965957022] linearizableReadLoop","detail":"{readStateIndex:1489; appliedIndex:1488; }","duration":"149.360514ms","start":"2026-05-21T19:35:02.893842Z","end":"2026-05-21T19:35:03.043202Z","steps":["trace[1965957022] 'read index received' (duration: 149.063377ms)","trace[1965957022] 'applied index is now lower than readState.Index' (duration: 296.057µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:03.043321Z","caller":"traceutil/trace.go:171","msg":"trace[1224134985] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"157.565648ms","start":"2026-05-21T19:35:02.885704Z","end":"2026-05-21T19:35:03.04327Z","steps":["trace[1224134985] 'process raft request' (duration: 157.22714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:03.043625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.745713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-21T19:35:03.043741Z","caller":"traceutil/trace.go:171","msg":"trace[878953713] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1440; }","duration":"149.906467ms","start":"2026-05-21T19:35:02.893808Z","end":"2026-05-21T19:35:03.043714Z","steps":["trace[878953713] 'agreement among raft nodes before linearized reading' (duration: 149.528088ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:18.863713Z","caller":"traceutil/trace.go:171","msg":"trace[485578013] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"146.744068ms","start":"2026-05-21T19:35:18.716929Z","end":"2026-05-21T19:35:18.863673Z","steps":["trace[485578013] 'process raft request' (duration: 144.643766ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:20.872421Z","caller":"traceutil/trace.go:171","msg":"trace[1708021437] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"415.14704ms","start":"2026-05-21T19:35:20.457243Z","end":"2026-05-21T19:35:20.87239Z","steps":["trace[1708021437] 'process raft request' (duration: 414.974286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:20.872619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.457215Z","time spent":"415.299914ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:20.8962Z","caller":"traceutil/trace.go:171","msg":"trace[1900101323] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"437.081488ms","start":"2026-05-21T19:35:20.459087Z","end":"2026-05-21T19:35:20.896169Z","steps":["trace[1900101323] 'process raft request' (duration: 437.014286ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:20.896245Z","caller":"traceutil/trace.go:171","msg":"trace[1416710575] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"437.59068ms","start":"2026-05-21T19:35:20.458601Z","end":"2026-05-21T19:35:20.896192Z","steps":["trace[1416710575] 'process raft request' (duration: 437.350154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:20.896395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.459073Z","time spent":"437.216302ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:20.896591Z","caller":"traceutil/trace.go:171","msg":"trace[653385665] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"436.579106ms","start":"2026-05-21T19:35:20.459995Z","end":"2026-05-21T19:35:20.896574Z","steps":["trace[653385665] 'process raft request' (duration: 436.135615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:20.896596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.458579Z","time spent":"437.881598ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:20.896671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.459979Z","time spent":"436.652257ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:20.896729Z","caller":"traceutil/trace.go:171","msg":"trace[1580771443] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"337.467504ms","start":"2026-05-21T19:35:20.559232Z","end":"2026-05-21T19:35:20.8967Z","steps":["trace[1580771443] 'process raft request' (duration: 337.402602ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:20.896823Z","caller":"traceutil/trace.go:171","msg":"trace[1942091891] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"394.002664ms","start":"2026-05-21T19:35:20.502805Z","end":"2026-05-21T19:35:20.896808Z","steps":["trace[1942091891] 'process raft request' (duration: 393.602324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:20.896907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.50278Z","time spent":"394.086626ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:20.897048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:20.559202Z","time spent":"337.625088ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.736692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211595389s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:29.736862Z","caller":"traceutil/trace.go:171","msg":"trace[1183387153] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1847; }","duration":"1.211778474s","start":"2026-05-21T19:35:28.525063Z","end":"2026-05-21T19:35:29.736841Z","steps":["trace[1183387153] 'range keys from in-memory index tree' (duration: 1.211576678s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.737388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036036821s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:29.737728Z","caller":"traceutil/trace.go:171","msg":"trace[1255336182] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"1.304428823s","start":"2026-05-21T19:35:28.433278Z","end":"2026-05-21T19:35:29.737707Z","steps":["trace[1255336182] 'process raft request' (duration: 1.30429178s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.73786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.433269Z","time spent":"1.304547197s","remote":"127.0.0.1:53530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:29.738121Z","caller":"traceutil/trace.go:171","msg":"trace[1595375692] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"1.305685644s","start":"2026-05-21T19:35:28.432321Z","end":"2026-05-21T19:35:29.738007Z","steps":["trace[1595375692] 'process raft request' (duration: 268.913845ms)","trace[1595375692] 'compare' (duration: 1.035897718s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:29.738163Z","caller":"traceutil/trace.go:171","msg":"trace[1610282700] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"1.303261344s","start":"2026-05-21T19:35:28.434818Z","end":"2026-05-21T19:35:29.73808Z","steps":["trace[1610282700] 'process raft request' (duration: 1.302825083s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.738327Z","caller":"traceutil/trace.go:171","msg":"trace[1972928686] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"1.298947486s","start":"2026-05-21T19:35:28.439363Z","end":"2026-05-21T19:35:29.73831Z","steps":["trace[1972928686] 'process raft request' (duration: 1.298823683s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.738341Z","caller":"traceutil/trace.go:171","msg":"trace[640288166] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1923; }","duration":"829.729027ms","start":"2026-05-21T19:35:28.908577Z","end":"2026-05-21T19:35:29.738306Z","steps":["trace[640288166] 'read index received' (duration: 382.203209ms)","trace[640288166] 'applied index is now lower than readState.Index' (duration: 447.524368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:29.738406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.434795Z","time spent":"1.303485179s","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.738396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.432269Z","time spent":"1.305992202s","remote":"127.0.0.1:53530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.738512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.43935Z","time spent":"1.299039788s","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.738668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.073996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:35:29.738692Z","caller":"traceutil/trace.go:171","msg":"trace[1436099937] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"1.303816778s","start":"2026-05-21T19:35:28.434814Z","end":"2026-05-21T19:35:29.738631Z","steps":["trace[1436099937] 'process raft request' (duration: 1.303216933s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.738717Z","caller":"traceutil/trace.go:171","msg":"trace[2022258606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1853; }","duration":"830.184638ms","start":"2026-05-21T19:35:28.908518Z","end":"2026-05-21T19:35:29.738703Z","steps":["trace[2022258606] 'agreement among raft nodes before linearized reading' (duration: 829.969363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.738763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.908491Z","time spent":"830.26229ms","remote":"127.0.0.1:53632","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":"info","ts":"2026-05-21T19:35:29.73895Z","caller":"traceutil/trace.go:171","msg":"trace[40769465] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"813.80055ms","start":"2026-05-21T19:35:28.925135Z","end":"2026-05-21T19:35:29.738936Z","steps":["trace[40769465] 'process raft request' (duration: 813.118683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.738998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.585966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-21T19:35:29.739053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.925122Z","time spent":"813.892052ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.73895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.055456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:35:29.739127Z","caller":"traceutil/trace.go:171","msg":"trace[876688836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"418.75105ms","start":"2026-05-21T19:35:29.320333Z","end":"2026-05-21T19:35:29.739084Z","steps":["trace[876688836] 'agreement among raft nodes before linearized reading' (duration: 418.451982ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.739334Z","caller":"traceutil/trace.go:171","msg":"trace[989445296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1854; }","duration":"806.445757ms","start":"2026-05-21T19:35:28.932878Z","end":"2026-05-21T19:35:29.739324Z","steps":["trace[989445296] 'agreement among raft nodes before linearized reading' (duration: 805.871122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.739326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.410427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-05-21T19:35:29.739364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.320313Z","time spent":"419.041018ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T19:35:29.739362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.940975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:35:29.73942Z","caller":"traceutil/trace.go:171","msg":"trace[329922622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944; range_end:; response_count:1; response_revision:1854; }","duration":"561.738446ms","start":"2026-05-21T19:35:29.177658Z","end":"2026-05-21T19:35:29.739396Z","steps":["trace[329922622] 'agreement among raft nodes before linearized reading' (duration: 561.358246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.73945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.054138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:35:29.739491Z","caller":"traceutil/trace.go:171","msg":"trace[878367021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1854; }","duration":"806.109268ms","start":"2026-05-21T19:35:28.93337Z","end":"2026-05-21T19:35:29.739479Z","steps":["trace[878367021] 'agreement among raft nodes before linearized reading' (duration: 806.000326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.739053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.697108ms","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:5"} {"level":"warn","ts":"2026-05-21T19:35:29.739607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.177629Z","time spent":"561.837718ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944\" "} {"level":"warn","ts":"2026-05-21T19:35:29.739368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.93281Z","time spent":"806.5505ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:35:29.739728Z","caller":"traceutil/trace.go:171","msg":"trace[170306515] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1854; }","duration":"425.356776ms","start":"2026-05-21T19:35:29.314291Z","end":"2026-05-21T19:35:29.739648Z","steps":["trace[170306515] 'agreement among raft nodes before linearized reading' (duration: 424.693398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.739775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.31428Z","time spent":"425.481288ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:35:29.738956Z","caller":"traceutil/trace.go:171","msg":"trace[135051994] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"159.036694ms","start":"2026-05-21T19:35:29.579885Z","end":"2026-05-21T19:35:29.738922Z","steps":["trace[135051994] 'process raft request' (duration: 158.705916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.739531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.933362Z","time spent":"806.162341ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:35:29.739593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.057164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:35:29.739542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.335882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:29.741324Z","caller":"traceutil/trace.go:171","msg":"trace[529167796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"471.167077ms","start":"2026-05-21T19:35:29.270134Z","end":"2026-05-21T19:35:29.741301Z","steps":["trace[529167796] 'agreement among raft nodes before linearized reading' (duration: 469.338352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.741405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.270108Z","time spent":"471.28097ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:35:29.740896Z","caller":"traceutil/trace.go:171","msg":"trace[1254810389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1854; }","duration":"188.383946ms","start":"2026-05-21T19:35:29.55248Z","end":"2026-05-21T19:35:29.740864Z","steps":["trace[1254810389] 'agreement among raft nodes before linearized reading' (duration: 187.082925ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.740369Z","caller":"traceutil/trace.go:171","msg":"trace[96759316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"806.020067ms","start":"2026-05-21T19:35:28.933387Z","end":"2026-05-21T19:35:29.739407Z","steps":["trace[96759316] 'agreement among raft nodes before linearized reading' (duration: 805.689068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.740445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.434775Z","time spent":"1.303973012s","remote":"127.0.0.1:53634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.741842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.933347Z","time spent":"808.475927ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:35:30.009337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.003197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:35:30.00946Z","caller":"traceutil/trace.go:171","msg":"trace[1661032125] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1859; }","duration":"133.213642ms","start":"2026-05-21T19:35:29.876217Z","end":"2026-05-21T19:35:30.00943Z","steps":["trace[1661032125] 'count revisions from in-memory index tree' (duration: 132.861093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:31.058325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.615956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:31.058444Z","caller":"traceutil/trace.go:171","msg":"trace[748773485] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"386.102226ms","start":"2026-05-21T19:35:30.672321Z","end":"2026-05-21T19:35:31.058423Z","steps":["trace[748773485] 'read index received' (duration: 80.802µs)","trace[748773485] 'applied index is now lower than readState.Index' (duration: 386.019354ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:31.058494Z","caller":"traceutil/trace.go:171","msg":"trace[1389540812] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"743.884196ms","start":"2026-05-21T19:35:30.314554Z","end":"2026-05-21T19:35:31.058438Z","steps":["trace[1389540812] 'process raft request' (duration: 27.035474ms)","trace[1389540812] 'compare' (duration: 716.257728ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:31.05867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.352723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:35:31.058685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:30.31452Z","time spent":"744.060441ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:31.058717Z","caller":"traceutil/trace.go:171","msg":"trace[1789836306] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1862; }","duration":"386.444956ms","start":"2026-05-21T19:35:30.672258Z","end":"2026-05-21T19:35:31.058703Z","steps":["trace[1789836306] 'agreement among raft nodes before linearized reading' (duration: 386.364254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:31.058794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:30.672226Z","time spent":"386.555358ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:31.059327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.559055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:31.059366Z","caller":"traceutil/trace.go:171","msg":"trace[433490832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"134.643627ms","start":"2026-05-21T19:35:30.924712Z","end":"2026-05-21T19:35:31.059356Z","steps":["trace[433490832] 'agreement among raft nodes before linearized reading' (duration: 134.577995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:31.059086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.535669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-05-21T19:35:31.059979Z","caller":"traceutil/trace.go:171","msg":"trace[660920964] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944; range_end:; response_count:1; response_revision:1862; }","duration":"312.534833ms","start":"2026-05-21T19:35:30.747427Z","end":"2026-05-21T19:35:31.059962Z","steps":["trace[660920964] 'agreement among raft nodes before linearized reading' (duration: 311.408085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:31.060045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:30.747398Z","time spent":"312.629595ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc5577ff-3241-4841-88ca-d361e58d0944\" "} {"level":"warn","ts":"2026-05-21T19:35:31.059562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.048236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-21T19:35:31.060196Z","caller":"traceutil/trace.go:171","msg":"trace[1620915712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"311.739822ms","start":"2026-05-21T19:35:30.748414Z","end":"2026-05-21T19:35:31.060154Z","steps":["trace[1620915712] 'agreement among raft nodes before linearized reading' (duration: 310.913722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:31.060318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:30.748382Z","time spent":"311.890226ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-21T19:35:35.706676Z","caller":"traceutil/trace.go:171","msg":"trace[1730582811] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"165.24907ms","start":"2026-05-21T19:35:35.541393Z","end":"2026-05-21T19:35:35.706642Z","steps":["trace[1730582811] 'process raft request' (duration: 165.031995ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.983918Z","caller":"traceutil/trace.go:171","msg":"trace[1067619539] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"265.325056ms","start":"2026-05-21T19:35:35.718569Z","end":"2026-05-21T19:35:35.983894Z","steps":["trace[1067619539] 'process raft request' (duration: 263.50891ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.985525Z","caller":"traceutil/trace.go:171","msg":"trace[1862153158] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"223.902702ms","start":"2026-05-21T19:35:35.761594Z","end":"2026-05-21T19:35:35.985496Z","steps":["trace[1862153158] 'process raft request' (duration: 223.734978ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.985818Z","caller":"traceutil/trace.go:171","msg":"trace[1273782545] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"223.513952ms","start":"2026-05-21T19:35:35.762254Z","end":"2026-05-21T19:35:35.985768Z","steps":["trace[1273782545] 'process raft request' (duration: 223.196504ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.986071Z","caller":"traceutil/trace.go:171","msg":"trace[358103201] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1983; }","duration":"188.580602ms","start":"2026-05-21T19:35:35.797467Z","end":"2026-05-21T19:35:35.986048Z","steps":["trace[358103201] 'read index received' (duration: 184.748667ms)","trace[358103201] 'applied index is now lower than readState.Index' (duration: 3.830535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:35.986307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.821528ms","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-21T19:35:35.986497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.26497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-21T19:35:35.986496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.599277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:35:35.986557Z","caller":"traceutil/trace.go:171","msg":"trace[463440028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1909; }","duration":"118.365552ms","start":"2026-05-21T19:35:35.868173Z","end":"2026-05-21T19:35:35.986538Z","steps":["trace[463440028] 'agreement among raft nodes before linearized reading' (duration: 118.148106ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.986561Z","caller":"traceutil/trace.go:171","msg":"trace[915827824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1909; }","duration":"122.70966ms","start":"2026-05-21T19:35:35.863836Z","end":"2026-05-21T19:35:35.986545Z","steps":["trace[915827824] 'agreement among raft nodes before linearized reading' (duration: 122.521495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:35.986438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.660638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T19:35:35.986606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.103419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-21T19:35:35.986654Z","caller":"traceutil/trace.go:171","msg":"trace[756643910] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1909; }","duration":"123.194451ms","start":"2026-05-21T19:35:35.863443Z","end":"2026-05-21T19:35:35.986637Z","steps":["trace[756643910] 'agreement among raft nodes before linearized reading' (duration: 122.879533ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.986669Z","caller":"traceutil/trace.go:171","msg":"trace[1059311616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1909; }","duration":"122.878594ms","start":"2026-05-21T19:35:35.863758Z","end":"2026-05-21T19:35:35.986636Z","steps":["trace[1059311616] 'agreement among raft nodes before linearized reading' (duration: 122.610508ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.98693Z","caller":"traceutil/trace.go:171","msg":"trace[1880268029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"189.047474ms","start":"2026-05-21T19:35:35.797451Z","end":"2026-05-21T19:35:35.986498Z","steps":["trace[1880268029] 'agreement among raft nodes before linearized reading' (duration: 188.710514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:43.077131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.254511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:43.077263Z","caller":"traceutil/trace.go:171","msg":"trace[701457001] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"210.835817ms","start":"2026-05-21T19:35:42.866402Z","end":"2026-05-21T19:35:43.077238Z","steps":["trace[701457001] 'read index received' (duration: 41.352µs)","trace[701457001] 'applied index is now lower than readState.Index' (duration: 210.792665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:43.077533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.718472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:43.077546Z","caller":"traceutil/trace.go:171","msg":"trace[537984603] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"499.550575ms","start":"2026-05-21T19:35:42.577928Z","end":"2026-05-21T19:35:43.077479Z","steps":["trace[537984603] 'process raft request' (duration: 272.787661ms)","trace[537984603] 'compare' (duration: 225.654866ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:43.077615Z","caller":"traceutil/trace.go:171","msg":"trace[1338000402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"153.837896ms","start":"2026-05-21T19:35:42.923757Z","end":"2026-05-21T19:35:43.077594Z","steps":["trace[1338000402] 'agreement among raft nodes before linearized reading' (duration: 153.688472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:43.077643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.196026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T19:35:43.077736Z","caller":"traceutil/trace.go:171","msg":"trace[1650921370] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:1970; }","duration":"211.35895ms","start":"2026-05-21T19:35:42.866345Z","end":"2026-05-21T19:35:43.077704Z","steps":["trace[1650921370] 'agreement among raft nodes before linearized reading' (duration: 211.078113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:43.077754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:42.577905Z","time spent":"499.744679ms","remote":"127.0.0.1:53802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6152,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:43.451587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.539741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:43.451732Z","caller":"traceutil/trace.go:171","msg":"trace[711475204] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"289.16938ms","start":"2026-05-21T19:35:43.162539Z","end":"2026-05-21T19:35:43.451708Z","steps":["trace[711475204] 'read index received' (duration: 25.331112ms)","trace[711475204] 'applied index is now lower than readState.Index' (duration: 263.836748ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:43.451848Z","caller":"traceutil/trace.go:171","msg":"trace[300770998] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"319.584238ms","start":"2026-05-21T19:35:43.132174Z","end":"2026-05-21T19:35:43.451758Z","steps":["trace[300770998] 'process raft request' (duration: 55.75352ms)","trace[300770998] 'compare' (duration: 263.223213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:43.45213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:43.13215Z","time spent":"319.929567ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:43.452468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.895909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-21T19:35:43.452644Z","caller":"traceutil/trace.go:171","msg":"trace[1321234652] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"290.058292ms","start":"2026-05-21T19:35:43.162499Z","end":"2026-05-21T19:35:43.452557Z","steps":["trace[1321234652] 'agreement among raft nodes before linearized reading' (duration: 289.384234ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:43.598318Z","caller":"traceutil/trace.go:171","msg":"trace[921570627] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"106.626878ms","start":"2026-05-21T19:35:43.491666Z","end":"2026-05-21T19:35:43.598293Z","steps":["trace[921570627] 'process raft request' (duration: 67.749469ms)","trace[921570627] 'compare' (duration: 38.737136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:36:20.72573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540169719,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:36:21.047373Z","caller":"traceutil/trace.go:171","msg":"trace[1865553739] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"878.888261ms","start":"2026-05-21T19:36:20.168456Z","end":"2026-05-21T19:36:21.047344Z","steps":["trace[1865553739] 'process raft request' (duration: 878.698777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.04761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.16842Z","time spent":"879.046065ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:36:21.897191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.423064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:36:21.897493Z","caller":"traceutil/trace.go:171","msg":"trace[822831021] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"754.485741ms","start":"2026-05-21T19:36:21.142979Z","end":"2026-05-21T19:36:21.897465Z","steps":["trace[822831021] 'process raft request' (duration: 754.361718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.897598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:21.142948Z","time spent":"754.601144ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:36:21.897622Z","caller":"traceutil/trace.go:171","msg":"trace[1659445445] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2261; }","duration":"1.672090669s","start":"2026-05-21T19:36:20.225492Z","end":"2026-05-21T19:36:21.897582Z","steps":["trace[1659445445] 'read index received' (duration: 821.764338ms)","trace[1659445445] 'applied index is now lower than readState.Index' (duration: 850.322311ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:21.897726Z","caller":"traceutil/trace.go:171","msg":"trace[1609126782] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"1.720634069s","start":"2026-05-21T19:36:20.177026Z","end":"2026-05-21T19:36:21.89766Z","steps":["trace[1609126782] 'process raft request' (duration: 1.321622051s)","trace[1609126782] 'compare' (duration: 397.736007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:36:21.897959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.176961Z","time spent":"1.720864074s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:36:21.897987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672471849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T19:36:21.89801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069831653s","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-21T19:36:21.898055Z","caller":"traceutil/trace.go:171","msg":"trace[279862510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2176; }","duration":"1.672606612s","start":"2026-05-21T19:36:20.225426Z","end":"2026-05-21T19:36:21.898033Z","steps":["trace[279862510] 'agreement among raft nodes before linearized reading' (duration: 1.672280964s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:21.898111Z","caller":"traceutil/trace.go:171","msg":"trace[684208564] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2176; }","duration":"1.07007622s","start":"2026-05-21T19:36:20.828008Z","end":"2026-05-21T19:36:21.898084Z","steps":["trace[684208564] 'agreement among raft nodes before linearized reading' (duration: 1.069808802s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.225387Z","time spent":"1.672729456s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:36:21.898145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.075457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:36:21.898193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430777943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-05-21T19:36:21.898227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671327551s","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-21T19:36:21.898258Z","caller":"traceutil/trace.go:171","msg":"trace[977530405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"976.240481ms","start":"2026-05-21T19:36:20.922003Z","end":"2026-05-21T19:36:21.898243Z","steps":["trace[977530405] 'agreement among raft nodes before linearized reading' (duration: 976.031225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.827978Z","time spent":"1.070216683s","remote":"127.0.0.1:49596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:36:21.898214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.292499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1abbd1a30c869\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-21T19:36:21.898265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320020063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:36:21.898314Z","caller":"traceutil/trace.go:171","msg":"trace[613379299] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2176; }","duration":"1.430946208s","start":"2026-05-21T19:36:20.467328Z","end":"2026-05-21T19:36:21.898274Z","steps":["trace[613379299] 'agreement among raft nodes before linearized reading' (duration: 1.430557708s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.68725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"warn","ts":"2026-05-21T19:36:21.898336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.921978Z","time spent":"976.346353ms","remote":"127.0.0.1:53330","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-21T19:36:21.898362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.467304Z","time spent":"1.43104723s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:36:21.89831Z","caller":"traceutil/trace.go:171","msg":"trace[185178287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"1.671416542s","start":"2026-05-21T19:36:20.226868Z","end":"2026-05-21T19:36:21.898284Z","steps":["trace[185178287] 'agreement among raft nodes before linearized reading' (duration: 1.671288069s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:21.898363Z","caller":"traceutil/trace.go:171","msg":"trace[1883887136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2176; }","duration":"1.320145266s","start":"2026-05-21T19:36:20.578194Z","end":"2026-05-21T19:36:21.89834Z","steps":["trace[1883887136] 'agreement among raft nodes before linearized reading' (duration: 1.31991933s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15588638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-21T19:36:21.898395Z","caller":"traceutil/trace.go:171","msg":"trace[477273016] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2176; }","duration":"199.885834ms","start":"2026-05-21T19:36:21.698479Z","end":"2026-05-21T19:36:21.898365Z","steps":["trace[477273016] 'agreement among raft nodes before linearized reading' (duration: 199.413342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.578172Z","time spent":"1.320250978s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:36:21.898471Z","caller":"traceutil/trace.go:171","msg":"trace[67337998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2176; }","duration":"1.156009083s","start":"2026-05-21T19:36:20.74242Z","end":"2026-05-21T19:36:21.898429Z","steps":["trace[67337998] 'agreement among raft nodes before linearized reading' (duration: 1.155685585s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.226855Z","time spent":"1.671575277s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:36:21.898522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.742347Z","time spent":"1.156165797s","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T19:36:21.89808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672073238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T19:36:21.898615Z","caller":"traceutil/trace.go:171","msg":"trace[517753056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"1.672662103s","start":"2026-05-21T19:36:20.225936Z","end":"2026-05-21T19:36:21.898598Z","steps":["trace[517753056] 'agreement among raft nodes before linearized reading' (duration: 1.671887593s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.898362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668821218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-21T19:36:21.898671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.225923Z","time spent":"1.672732316s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:36:21.898701Z","caller":"traceutil/trace.go:171","msg":"trace[1267732493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2176; }","duration":"1.669212457s","start":"2026-05-21T19:36:20.229469Z","end":"2026-05-21T19:36:21.898682Z","steps":["trace[1267732493] 'agreement among raft nodes before linearized reading' (duration: 1.668737126s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.8986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671429783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-21T19:36:21.89836Z","caller":"traceutil/trace.go:171","msg":"trace[1237524787] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b1abbd1a30c869; range_end:; response_count:1; response_revision:2176; }","duration":"436.516574ms","start":"2026-05-21T19:36:21.4618Z","end":"2026-05-21T19:36:21.898316Z","steps":["trace[1237524787] 'agreement among raft nodes before linearized reading' (duration: 436.063612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.89877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.22944Z","time spent":"1.66931656s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:36:21.898826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:21.461774Z","time spent":"437.034456ms","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1abbd1a30c869\" "} {"level":"info","ts":"2026-05-21T19:36:21.898816Z","caller":"traceutil/trace.go:171","msg":"trace[686293369] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2176; }","duration":"1.67171112s","start":"2026-05-21T19:36:20.227039Z","end":"2026-05-21T19:36:21.89875Z","steps":["trace[686293369] 'agreement among raft nodes before linearized reading' (duration: 1.671091745s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:21.899148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:20.227025Z","time spent":"1.672109259s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:36:22.10014Z","caller":"traceutil/trace.go:171","msg":"trace[1854622449] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"197.644599ms","start":"2026-05-21T19:36:21.902468Z","end":"2026-05-21T19:36:22.100113Z","steps":["trace[1854622449] 'process raft request' (duration: 197.465205ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.105111Z","caller":"traceutil/trace.go:171","msg":"trace[1884300695] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"200.894448ms","start":"2026-05-21T19:36:21.904199Z","end":"2026-05-21T19:36:22.105093Z","steps":["trace[1884300695] 'process raft request' (duration: 200.722724ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.10534Z","caller":"traceutil/trace.go:171","msg":"trace[377211496] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"200.806366ms","start":"2026-05-21T19:36:21.904521Z","end":"2026-05-21T19:36:22.105327Z","steps":["trace[377211496] 'process raft request' (duration: 200.523439ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.105723Z","caller":"traceutil/trace.go:171","msg":"trace[1449451432] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"201.021872ms","start":"2026-05-21T19:36:21.90469Z","end":"2026-05-21T19:36:22.105712Z","steps":["trace[1449451432] 'process raft request' (duration: 200.851818ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.105624Z","caller":"traceutil/trace.go:171","msg":"trace[1260861083] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"201.011703ms","start":"2026-05-21T19:36:21.904593Z","end":"2026-05-21T19:36:22.105605Z","steps":["trace[1260861083] 'process raft request' (duration: 200.690084ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.105997Z","caller":"traceutil/trace.go:171","msg":"trace[622328295] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2264; }","duration":"197.828123ms","start":"2026-05-21T19:36:21.90815Z","end":"2026-05-21T19:36:22.105978Z","steps":["trace[622328295] 'read index received' (duration: 191.776532ms)","trace[622328295] 'applied index is now lower than readState.Index' (duration: 6.050441ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:22.10602Z","caller":"traceutil/trace.go:171","msg":"trace[1510836596] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"200.863659ms","start":"2026-05-21T19:36:21.905117Z","end":"2026-05-21T19:36:22.10598Z","steps":["trace[1510836596] 'process raft request' (duration: 200.729906ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.106058Z","caller":"traceutil/trace.go:171","msg":"trace[2062116714] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"200.602593ms","start":"2026-05-21T19:36:21.905437Z","end":"2026-05-21T19:36:22.10604Z","steps":["trace[2062116714] 'process raft request' (duration: 200.50009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:22.106193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.019638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:36:22.106233Z","caller":"traceutil/trace.go:171","msg":"trace[663381980] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2184; }","duration":"198.09023ms","start":"2026-05-21T19:36:21.90813Z","end":"2026-05-21T19:36:22.10622Z","steps":["trace[663381980] 'agreement among raft nodes before linearized reading' (duration: 197.989897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:22.106281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.94732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:36:22.106322Z","caller":"traceutil/trace.go:171","msg":"trace[536349409] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"201.147126ms","start":"2026-05-21T19:36:21.904794Z","end":"2026-05-21T19:36:22.105942Z","steps":["trace[536349409] 'process raft request' (duration: 200.876929ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:22.10635Z","caller":"traceutil/trace.go:171","msg":"trace[1475484099] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2184; }","duration":"126.035643ms","start":"2026-05-21T19:36:21.980292Z","end":"2026-05-21T19:36:22.106327Z","steps":["trace[1475484099] 'agreement among raft nodes before linearized reading' (duration: 125.91263ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:31.416243Z","caller":"traceutil/trace.go:171","msg":"trace[723394889] transaction","detail":"{read_only:false; response_revision:3737; number_of_response:1; }","duration":"113.415435ms","start":"2026-05-21T19:40:31.302798Z","end":"2026-05-21T19:40:31.416214Z","steps":["trace[723394889] 'process raft request' (duration: 113.248051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:31.644255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.051679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-21T19:40:31.644338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.350862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:40:31.6444Z","caller":"traceutil/trace.go:171","msg":"trace[1113869653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3737; }","duration":"131.342776ms","start":"2026-05-21T19:40:31.513026Z","end":"2026-05-21T19:40:31.644369Z","steps":["trace[1113869653] 'range keys from in-memory index tree' (duration: 130.849723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:31.644396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.895315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:40:31.644434Z","caller":"traceutil/trace.go:171","msg":"trace[103587656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3737; }","duration":"130.488726ms","start":"2026-05-21T19:40:31.513922Z","end":"2026-05-21T19:40:31.644411Z","steps":["trace[103587656] 'range keys from in-memory index tree' (duration: 130.178538ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:31.644473Z","caller":"traceutil/trace.go:171","msg":"trace[53807488] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3737; }","duration":"122.049648ms","start":"2026-05-21T19:40:31.522401Z","end":"2026-05-21T19:40:31.644451Z","steps":["trace[53807488] 'range keys from in-memory index tree' (duration: 121.694549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:31.644467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.042413ms","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-21T19:40:31.644596Z","caller":"traceutil/trace.go:171","msg":"trace[542525606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3737; }","duration":"132.222297ms","start":"2026-05-21T19:40:31.512349Z","end":"2026-05-21T19:40:31.644572Z","steps":["trace[542525606] 'range keys from in-memory index tree' (duration: 131.833648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:31.644473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.665336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:40:31.644777Z","caller":"traceutil/trace.go:171","msg":"trace[1264623956] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3737; }","duration":"138.979513ms","start":"2026-05-21T19:40:31.505756Z","end":"2026-05-21T19:40:31.644735Z","steps":["trace[1264623956] 'range keys from in-memory index tree' (duration: 138.465941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:31.644476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.406528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-21T19:40:31.644835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.278781ms","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-21T19:40:31.644874Z","caller":"traceutil/trace.go:171","msg":"trace[633128856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3737; }","duration":"142.879049ms","start":"2026-05-21T19:40:31.501971Z","end":"2026-05-21T19:40:31.64485Z","steps":["trace[633128856] 'range keys from in-memory index tree' (duration: 142.173712ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:31.644884Z","caller":"traceutil/trace.go:171","msg":"trace[64112469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3737; }","duration":"141.344642ms","start":"2026-05-21T19:40:31.503525Z","end":"2026-05-21T19:40:31.64487Z","steps":["trace[64112469] 'range keys from in-memory index tree' (duration: 140.619444ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:50.715527Z","caller":"traceutil/trace.go:171","msg":"trace[895425336] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"122.572646ms","start":"2026-05-21T19:41:50.59292Z","end":"2026-05-21T19:41:50.715492Z","steps":["trace[895425336] 'process raft request' (duration: 121.849888ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:52.573824Z","caller":"traceutil/trace.go:171","msg":"trace[1610480654] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"100.567182ms","start":"2026-05-21T19:41:52.473225Z","end":"2026-05-21T19:41:52.573793Z","steps":["trace[1610480654] 'process raft request' (duration: 85.065617ms)","trace[1610480654] 'compare' (duration: 15.355571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:41:57.468577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.840362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:41:57.468702Z","caller":"traceutil/trace.go:171","msg":"trace[332172964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4916; }","duration":"546.011706ms","start":"2026-05-21T19:41:56.922665Z","end":"2026-05-21T19:41:57.468677Z","steps":["trace[332172964] 'range keys from in-memory index tree' (duration: 545.77369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:57.46871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.146469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"warn","ts":"2026-05-21T19:41:57.468799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.830235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:41:57.468813Z","caller":"traceutil/trace.go:171","msg":"trace[1989650481] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4916; }","duration":"546.308573ms","start":"2026-05-21T19:41:56.922481Z","end":"2026-05-21T19:41:57.468789Z","steps":["trace[1989650481] 'range keys from in-memory index tree' (duration: 545.894543ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:57.468843Z","caller":"traceutil/trace.go:171","msg":"trace[650859407] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4916; }","duration":"485.874566ms","start":"2026-05-21T19:41:56.982954Z","end":"2026-05-21T19:41:57.468828Z","steps":["trace[650859407] 'count revisions from in-memory index tree' (duration: 485.780134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:57.46887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:56.922458Z","time spent":"546.402065ms","remote":"127.0.0.1:53548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":917,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-05-21T19:41:57.468877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:56.982943Z","time spent":"485.925017ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:41:57.468992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.521527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-21T19:41:57.469031Z","caller":"traceutil/trace.go:171","msg":"trace[5542893] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7; range_end:; response_count:1; response_revision:4916; }","duration":"483.601299ms","start":"2026-05-21T19:41:56.985417Z","end":"2026-05-21T19:41:57.469019Z","steps":["trace[5542893] 'range keys from in-memory index tree' (duration: 483.375313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:57.469054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:56.985386Z","time spent":"483.6614ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7\" "} {"level":"warn","ts":"2026-05-21T19:41:57.468747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:56.922645Z","time spent":"546.092877ms","remote":"127.0.0.1:53330","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-21T19:41:57.469224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.773634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j5dnx\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:41:57.469368Z","caller":"traceutil/trace.go:171","msg":"trace[1698262520] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j5dnx; range_end:; response_count:1; response_revision:4916; }","duration":"460.968238ms","start":"2026-05-21T19:41:57.00837Z","end":"2026-05-21T19:41:57.469339Z","steps":["trace[1698262520] 'range keys from in-memory index tree' (duration: 459.952884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:57.469422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:57.008357Z","time spent":"461.053811ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-j5dnx\" "} {"level":"warn","ts":"2026-05-21T19:41:57.469178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.621683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:821480"} {"level":"info","ts":"2026-05-21T19:41:57.469606Z","caller":"traceutil/trace.go:171","msg":"trace[998989724] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4916; }","duration":"491.063575ms","start":"2026-05-21T19:41:56.978519Z","end":"2026-05-21T19:41:57.469583Z","steps":["trace[998989724] 'range keys from in-memory index tree' (duration: 490.07663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:57.469598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.715288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-21T19:41:57.469703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:56.978506Z","time spent":"491.182488ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":821503,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-05-21T19:41:57.469765Z","caller":"traceutil/trace.go:171","msg":"trace[1621304571] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"202.22824ms","start":"2026-05-21T19:41:57.267516Z","end":"2026-05-21T19:41:57.469744Z","steps":["trace[1621304571] 'process raft request' (duration: 29.253725ms)","trace[1621304571] 'compare' (duration: 171.672622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:41:57.970596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:57.619575Z","time spent":"351.011514ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T19:41:58.050321Z","caller":"traceutil/trace.go:171","msg":"trace[1425186759] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"369.984135ms","start":"2026-05-21T19:41:57.680299Z","end":"2026-05-21T19:41:58.050283Z","steps":["trace[1425186759] 'read index received' (duration: 369.7793ms)","trace[1425186759] 'applied index is now lower than readState.Index' (duration: 203.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:41:58.050492Z","caller":"traceutil/trace.go:171","msg":"trace[1381469396] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"548.113897ms","start":"2026-05-21T19:41:57.502301Z","end":"2026-05-21T19:41:58.050414Z","steps":["trace[1381469396] 'process raft request' (duration: 547.773099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.050572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.641193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:41:58.05067Z","caller":"traceutil/trace.go:171","msg":"trace[158258768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"127.782546ms","start":"2026-05-21T19:41:57.922865Z","end":"2026-05-21T19:41:58.050647Z","steps":["trace[158258768] 'agreement among raft nodes before linearized reading' (duration: 127.648663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.050579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.227461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:41:58.050762Z","caller":"traceutil/trace.go:171","msg":"trace[1595146677] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4918; }","duration":"370.492127ms","start":"2026-05-21T19:41:57.680252Z","end":"2026-05-21T19:41:58.050744Z","steps":["trace[1595146677] 'agreement among raft nodes before linearized reading' (duration: 370.161539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.050791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:57.680235Z","time spent":"370.546018ms","remote":"127.0.0.1:49604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:41:58.050795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:57.502277Z","time spent":"548.371384ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:41:58.713193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.019102ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:41:58.71335Z","caller":"traceutil/trace.go:171","msg":"trace[447753825] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4921; }","duration":"187.191276ms","start":"2026-05-21T19:41:58.526137Z","end":"2026-05-21T19:41:58.713328Z","steps":["trace[447753825] 'range keys from in-memory index tree' (duration: 186.994961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.713693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.59957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:41:58.714016Z","caller":"traceutil/trace.go:171","msg":"trace[1951407577] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"451.71886ms","start":"2026-05-21T19:41:58.262273Z","end":"2026-05-21T19:41:58.713992Z","steps":["trace[1951407577] 'process raft request' (duration: 451.628468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.714105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.262244Z","time spent":"451.824573ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:41:58.714139Z","caller":"traceutil/trace.go:171","msg":"trace[1665584237] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"451.831843ms","start":"2026-05-21T19:41:58.262246Z","end":"2026-05-21T19:41:58.714078Z","steps":["trace[1665584237] 'process raft request' (duration: 451.579727ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:58.714174Z","caller":"traceutil/trace.go:171","msg":"trace[1504081526] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"451.959536ms","start":"2026-05-21T19:41:58.262147Z","end":"2026-05-21T19:41:58.714107Z","steps":["trace[1504081526] 'process raft request' (duration: 340.799382ms)","trace[1504081526] 'compare' (duration: 110.418106ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:41:58.71426Z","caller":"traceutil/trace.go:171","msg":"trace[947020247] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5113; }","duration":"318.07381ms","start":"2026-05-21T19:41:58.396149Z","end":"2026-05-21T19:41:58.714223Z","steps":["trace[947020247] 'read index received' (duration: 206.836274ms)","trace[947020247] 'applied index is now lower than readState.Index' (duration: 111.235536ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:41:58.714428Z","caller":"traceutil/trace.go:171","msg":"trace[216982254] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"451.377761ms","start":"2026-05-21T19:41:58.263035Z","end":"2026-05-21T19:41:58.714413Z","steps":["trace[216982254] 'process raft request' (duration: 450.90935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.71443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.262208Z","time spent":"452.045118ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:41:58.71448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.262109Z","time spent":"452.181811ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:41:58.714507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.263023Z","time spent":"451.448763ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:41:58.714487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.315716ms","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:5"} {"level":"info","ts":"2026-05-21T19:41:58.714553Z","caller":"traceutil/trace.go:171","msg":"trace[730868689] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4925; }","duration":"318.478889ms","start":"2026-05-21T19:41:58.396059Z","end":"2026-05-21T19:41:58.714538Z","steps":["trace[730868689] 'agreement among raft nodes before linearized reading' (duration: 318.345406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:58.714802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.396021Z","time spent":"318.768816ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:41:59.124637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.497039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:41:59.124858Z","caller":"traceutil/trace.go:171","msg":"trace[345524476] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5117; }","duration":"410.471947ms","start":"2026-05-21T19:41:58.714366Z","end":"2026-05-21T19:41:59.124837Z","steps":["trace[345524476] 'read index received' (duration: 309.615879ms)","trace[345524476] 'applied index is now lower than readState.Index' (duration: 100.854588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:41:59.125149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.196902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:41:59.125171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.836271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:41:59.125208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.889631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:41:59.125316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.5779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-05-21T19:41:59.125324Z","caller":"traceutil/trace.go:171","msg":"trace[1863548706] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4927; }","duration":"727.406399ms","start":"2026-05-21T19:41:58.397839Z","end":"2026-05-21T19:41:59.125245Z","steps":["trace[1863548706] 'agreement among raft nodes before linearized reading' (duration: 727.155522ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:59.125406Z","caller":"traceutil/trace.go:171","msg":"trace[152716380] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7; range_end:; response_count:1; response_revision:4927; }","duration":"651.688193ms","start":"2026-05-21T19:41:58.473701Z","end":"2026-05-21T19:41:59.125389Z","steps":["trace[152716380] 'agreement among raft nodes before linearized reading' (duration: 651.401065ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:59.125173Z","caller":"traceutil/trace.go:171","msg":"trace[224070213] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"685.630863ms","start":"2026-05-21T19:41:58.439287Z","end":"2026-05-21T19:41:59.124918Z","steps":["trace[224070213] 'process raft request' (duration: 584.732474ms)","trace[224070213] 'compare' (duration: 100.178311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:41:59.12546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.47362Z","time spent":"651.830166ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3743,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6eede413-91a2-43a6-9a22-b742747a82e7\" "} {"level":"warn","ts":"2026-05-21T19:41:59.1255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.397809Z","time spent":"727.673595ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:41:59.125427Z","caller":"traceutil/trace.go:171","msg":"trace[1129142591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4927; }","duration":"202.133757ms","start":"2026-05-21T19:41:58.923232Z","end":"2026-05-21T19:41:59.125366Z","steps":["trace[1129142591] 'agreement among raft nodes before linearized reading' (duration: 201.88581ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:59.124943Z","caller":"traceutil/trace.go:171","msg":"trace[1305045169] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"566.162165ms","start":"2026-05-21T19:41:58.558738Z","end":"2026-05-21T19:41:59.1249Z","steps":["trace[1305045169] 'process raft request' (duration: 566.019601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:59.125583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.439262Z","time spent":"686.240718ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:41:59.125685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.558704Z","time spent":"566.925223ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:41:59.125305Z","caller":"traceutil/trace.go:171","msg":"trace[1263265011] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4927; }","duration":"297.039828ms","start":"2026-05-21T19:41:58.828233Z","end":"2026-05-21T19:41:59.125273Z","steps":["trace[1263265011] 'agreement among raft nodes before linearized reading' (duration: 296.805871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:59.125502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.55785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:821480"} {"level":"info","ts":"2026-05-21T19:41:59.126042Z","caller":"traceutil/trace.go:171","msg":"trace[766891521] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4927; }","duration":"587.153495ms","start":"2026-05-21T19:41:58.538873Z","end":"2026-05-21T19:41:59.126026Z","steps":["trace[766891521] 'agreement among raft nodes before linearized reading' (duration: 586.083998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:41:59.126202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:41:58.538848Z","time spent":"587.280558ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":821503,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-05-21T19:41:59.506174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.988563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:821480"} {"level":"info","ts":"2026-05-21T19:41:59.506301Z","caller":"traceutil/trace.go:171","msg":"trace[1573667680] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4928; }","duration":"135.209069ms","start":"2026-05-21T19:41:59.371069Z","end":"2026-05-21T19:41:59.506278Z","steps":["trace[1573667680] 'range keys from in-memory index tree' (duration: 134.00289ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:03.41309Z","caller":"traceutil/trace.go:171","msg":"trace[2034958755] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"105.675997ms","start":"2026-05-21T19:42:03.307389Z","end":"2026-05-21T19:42:03.413065Z","steps":["trace[2034958755] 'process raft request' (duration: 105.505273ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:06.376834Z","caller":"traceutil/trace.go:171","msg":"trace[1569953524] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"175.162169ms","start":"2026-05-21T19:42:06.201646Z","end":"2026-05-21T19:42:06.376808Z","steps":["trace[1569953524] 'process raft request' (duration: 175.014206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.754307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.135598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:42:06.754472Z","caller":"traceutil/trace.go:171","msg":"trace[28912321] linearizableReadLoop","detail":"{readStateIndex:5272; appliedIndex:5270; }","duration":"456.343186ms","start":"2026-05-21T19:42:06.298101Z","end":"2026-05-21T19:42:06.754444Z","steps":["trace[28912321] 'read index received' (duration: 78.501936ms)","trace[28912321] 'applied index is now lower than readState.Index' (duration: 377.83921ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:06.754811Z","caller":"traceutil/trace.go:171","msg":"trace[2136204897] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"532.235458ms","start":"2026-05-21T19:42:06.222539Z","end":"2026-05-21T19:42:06.754774Z","steps":["trace[2136204897] 'process raft request' (duration: 245.514177ms)","trace[2136204897] 'compare' (duration: 285.960013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:42:06.754961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.855258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-21T19:42:06.75502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.450176ms","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-21T19:42:06.75505Z","caller":"traceutil/trace.go:171","msg":"trace[528979205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5074; }","duration":"456.992012ms","start":"2026-05-21T19:42:06.298035Z","end":"2026-05-21T19:42:06.755027Z","steps":["trace[528979205] 'agreement among raft nodes before linearized reading' (duration: 456.742735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.222517Z","time spent":"532.444913ms","remote":"127.0.0.1:53534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:06.755117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.298008Z","time spent":"457.094014ms","remote":"127.0.0.1:53632","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-21T19:42:06.755077Z","caller":"traceutil/trace.go:171","msg":"trace[1488827667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5074; }","duration":"434.547988ms","start":"2026-05-21T19:42:06.320511Z","end":"2026-05-21T19:42:06.755059Z","steps":["trace[1488827667] 'agreement among raft nodes before linearized reading' (duration: 434.396275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.193035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"warn","ts":"2026-05-21T19:42:06.755234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.004891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-05-21T19:42:06.755287Z","caller":"traceutil/trace.go:171","msg":"trace[1005072804] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5074; }","duration":"373.290508ms","start":"2026-05-21T19:42:06.381981Z","end":"2026-05-21T19:42:06.755272Z","steps":["trace[1005072804] 'agreement among raft nodes before linearized reading' (duration: 373.131154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.320479Z","time spent":"434.749653ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:42:06.755339Z","caller":"traceutil/trace.go:171","msg":"trace[1162399919] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5074; }","duration":"165.123254ms","start":"2026-05-21T19:42:06.590192Z","end":"2026-05-21T19:42:06.755315Z","steps":["trace[1162399919] 'agreement among raft nodes before linearized reading' (duration: 164.821426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.394215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T19:42:06.755514Z","caller":"traceutil/trace.go:171","msg":"trace[2075967593] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5074; }","duration":"374.539518ms","start":"2026-05-21T19:42:06.380961Z","end":"2026-05-21T19:42:06.7555Z","steps":["trace[2075967593] 'agreement among raft nodes before linearized reading' (duration: 374.402895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.380936Z","time spent":"374.613799ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-05-21T19:42:06.755337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.381964Z","time spent":"373.361469ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-21T19:42:06.755852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.424112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:42:06.755919Z","caller":"traceutil/trace.go:171","msg":"trace[1817787183] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5074; }","duration":"429.518725ms","start":"2026-05-21T19:42:06.326382Z","end":"2026-05-21T19:42:06.755901Z","steps":["trace[1817787183] 'agreement among raft nodes before linearized reading' (duration: 429.32408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:06.755955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:06.326371Z","time spent":"429.576516ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:42:08.879548Z","caller":"traceutil/trace.go:171","msg":"trace[1664841075] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"100.494158ms","start":"2026-05-21T19:42:08.778993Z","end":"2026-05-21T19:42:08.879488Z","steps":["trace[1664841075] 'process raft request' (duration: 100.15951ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:08.986545Z","caller":"traceutil/trace.go:171","msg":"trace[1732633111] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"100.54263ms","start":"2026-05-21T19:42:08.88596Z","end":"2026-05-21T19:42:08.986502Z","steps":["trace[1732633111] 'process raft request' (duration: 100.226533ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:08.986592Z","caller":"traceutil/trace.go:171","msg":"trace[823547538] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"100.473739ms","start":"2026-05-21T19:42:08.886079Z","end":"2026-05-21T19:42:08.986553Z","steps":["trace[823547538] 'process raft request' (duration: 100.304324ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:08.986623Z","caller":"traceutil/trace.go:171","msg":"trace[922106427] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"100.442768ms","start":"2026-05-21T19:42:08.886153Z","end":"2026-05-21T19:42:08.986595Z","steps":["trace[922106427] 'process raft request' (duration: 100.273213ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:08.986769Z","caller":"traceutil/trace.go:171","msg":"trace[1939727510] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"100.52802ms","start":"2026-05-21T19:42:08.886194Z","end":"2026-05-21T19:42:08.986722Z","steps":["trace[1939727510] 'process raft request' (duration: 100.291734ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:08.986777Z","caller":"traceutil/trace.go:171","msg":"trace[519049645] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"100.162401ms","start":"2026-05-21T19:42:08.886573Z","end":"2026-05-21T19:42:08.986736Z","steps":["trace[519049645] 'process raft request' (duration: 100.033138ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:11.806937Z","caller":"traceutil/trace.go:171","msg":"trace[650577496] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5361; }","duration":"208.715337ms","start":"2026-05-21T19:42:11.59819Z","end":"2026-05-21T19:42:11.806905Z","steps":["trace[650577496] 'read index received' (duration: 208.333488ms)","trace[650577496] 'applied index is now lower than readState.Index' (duration: 380.519µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:11.807Z","caller":"traceutil/trace.go:171","msg":"trace[2095295491] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"210.352288ms","start":"2026-05-21T19:42:11.596589Z","end":"2026-05-21T19:42:11.806941Z","steps":["trace[2095295491] 'process raft request' (duration: 210.050621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:11.807275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.470117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-msxbw\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-05-21T19:42:11.807327Z","caller":"traceutil/trace.go:171","msg":"trace[925217493] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-msxbw; range_end:; response_count:1; response_revision:5162; }","duration":"164.573809ms","start":"2026-05-21T19:42:11.642738Z","end":"2026-05-21T19:42:11.807312Z","steps":["trace[925217493] 'agreement among raft nodes before linearized reading' (duration: 164.341143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:11.807396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.19304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:53897"} {"level":"info","ts":"2026-05-21T19:42:11.807492Z","caller":"traceutil/trace.go:171","msg":"trace[926039874] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5162; }","duration":"209.327753ms","start":"2026-05-21T19:42:11.598143Z","end":"2026-05-21T19:42:11.80747Z","steps":["trace[926039874] 'agreement among raft nodes before linearized reading' (duration: 208.925473ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:11.955747Z","caller":"traceutil/trace.go:171","msg":"trace[1828075938] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"136.816505ms","start":"2026-05-21T19:42:11.818884Z","end":"2026-05-21T19:42:11.9557Z","steps":["trace[1828075938] 'process raft request' (duration: 50.856635ms)","trace[1828075938] 'compare' (duration: 85.614821ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:11.956321Z","caller":"traceutil/trace.go:171","msg":"trace[452885787] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"132.853718ms","start":"2026-05-21T19:42:11.823441Z","end":"2026-05-21T19:42:11.956295Z","steps":["trace[452885787] 'process raft request' (duration: 132.12909ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:11.956645Z","caller":"traceutil/trace.go:171","msg":"trace[744485184] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"101.157895ms","start":"2026-05-21T19:42:11.855462Z","end":"2026-05-21T19:42:11.95662Z","steps":["trace[744485184] 'process raft request' (duration: 100.744725ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:11.956681Z","caller":"traceutil/trace.go:171","msg":"trace[528638561] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"132.534249ms","start":"2026-05-21T19:42:11.824131Z","end":"2026-05-21T19:42:11.956665Z","steps":["trace[528638561] 'process raft request' (duration: 131.825471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:12.674681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.104609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:42:12.674794Z","caller":"traceutil/trace.go:171","msg":"trace[2038190547] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5171; }","duration":"323.294934ms","start":"2026-05-21T19:42:12.351475Z","end":"2026-05-21T19:42:12.67477Z","steps":["trace[2038190547] 'count revisions from in-memory index tree' (duration: 322.977827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:12.674855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:12.351445Z","time spent":"323.388436ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:42:12.674967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.830994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:42:12.675022Z","caller":"traceutil/trace.go:171","msg":"trace[193327957] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5171; }","duration":"243.946707ms","start":"2026-05-21T19:42:12.43106Z","end":"2026-05-21T19:42:12.675007Z","steps":["trace[193327957] 'count revisions from in-memory index tree' (duration: 243.732342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:22.192091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.239715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:42:22.192212Z","caller":"traceutil/trace.go:171","msg":"trace[753601977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5290; }","duration":"184.43874ms","start":"2026-05-21T19:42:22.007746Z","end":"2026-05-21T19:42:22.192185Z","steps":["trace[753601977] 'count revisions from in-memory index tree' (duration: 184.088851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:22.192473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.574966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j5dnx\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:42:22.192602Z","caller":"traceutil/trace.go:171","msg":"trace[800275381] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j5dnx; range_end:; response_count:1; response_revision:5290; }","duration":"328.968275ms","start":"2026-05-21T19:42:21.8636Z","end":"2026-05-21T19:42:22.192569Z","steps":["trace[800275381] 'range keys from in-memory index tree' (duration: 328.313289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:22.192668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:21.863573Z","time spent":"329.077038ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-j5dnx\" "} {"level":"warn","ts":"2026-05-21T19:42:27.422762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993702516540177807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:42:27.665978Z","caller":"traceutil/trace.go:171","msg":"trace[1689290869] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"747.668782ms","start":"2026-05-21T19:42:26.918276Z","end":"2026-05-21T19:42:27.665945Z","steps":["trace[1689290869] 'process raft request' (duration: 747.456207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:27.666189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:26.918258Z","time spent":"747.816455ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:28.498211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.290239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:42:28.498492Z","caller":"traceutil/trace.go:171","msg":"trace[41371056] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5633; }","duration":"1.576547766s","start":"2026-05-21T19:42:26.921921Z","end":"2026-05-21T19:42:28.498469Z","steps":["trace[41371056] 'read index received' (duration: 743.828107ms)","trace[41371056] 'applied index is now lower than readState.Index' (duration: 832.718439ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:28.498778Z","caller":"traceutil/trace.go:171","msg":"trace[717686692] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"847.845843ms","start":"2026-05-21T19:42:27.650919Z","end":"2026-05-21T19:42:28.498765Z","steps":["trace[717686692] 'process raft request' (duration: 847.427113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:28.49882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576884095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:42:28.498859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.650884Z","time spent":"847.934775ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:28.49892Z","caller":"traceutil/trace.go:171","msg":"trace[1629373919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5431; }","duration":"1.577039959s","start":"2026-05-21T19:42:26.921861Z","end":"2026-05-21T19:42:28.498901Z","steps":["trace[1629373919] 'agreement among raft nodes before linearized reading' (duration: 1.576880685s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:28.498964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:26.921847Z","time spent":"1.5771042s","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:42:28.499026Z","caller":"traceutil/trace.go:171","msg":"trace[2021661447] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"1.26005702s","start":"2026-05-21T19:42:27.23837Z","end":"2026-05-21T19:42:28.498427Z","steps":["trace[2021661447] 'process raft request' (duration: 950.412932ms)","trace[2021661447] 'compare' (duration: 308.561811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:42:28.499168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.238334Z","time spent":"1.260748317s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:29.053569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.205586ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T19:42:29.053717Z","caller":"traceutil/trace.go:171","msg":"trace[2037725880] linearizableReadLoop","detail":"{readStateIndex:5637; appliedIndex:5636; }","duration":"555.161354ms","start":"2026-05-21T19:42:28.49853Z","end":"2026-05-21T19:42:29.053691Z","steps":["trace[2037725880] 'read index received' (duration: 3.741322ms)","trace[2037725880] 'applied index is now lower than readState.Index' (duration: 551.417862ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:42:29.053853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.722772ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:42:29.054049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.959897702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:42:29.054152Z","caller":"traceutil/trace.go:171","msg":"trace[1397096374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5431; }","duration":"1.960029414s","start":"2026-05-21T19:42:27.094101Z","end":"2026-05-21T19:42:29.05413Z","steps":["trace[1397096374] 'agreement among raft nodes before linearized reading' (duration: 1.959783608s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.05415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.962242989s","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-21T19:42:29.054199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.09409Z","time spent":"1.960097697s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:42:29.054255Z","caller":"traceutil/trace.go:171","msg":"trace[1040601759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5431; }","duration":"1.962341602s","start":"2026-05-21T19:42:27.091855Z","end":"2026-05-21T19:42:29.054196Z","steps":["trace[1040601759] 'agreement among raft nodes before linearized reading' (duration: 1.962051065s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971075787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-05-21T19:42:29.054267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124535407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-21T19:42:29.054084Z","caller":"traceutil/trace.go:171","msg":"trace[1934318897] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5431; }","duration":"528.969277ms","start":"2026-05-21T19:42:28.525084Z","end":"2026-05-21T19:42:29.054053Z","steps":["trace[1934318897] 'range keys from in-memory index tree' (duration: 528.701321ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.054327Z","caller":"traceutil/trace.go:171","msg":"trace[489648177] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5431; }","duration":"1.971240321s","start":"2026-05-21T19:42:27.083066Z","end":"2026-05-21T19:42:29.054307Z","steps":["trace[489648177] 'agreement among raft nodes before linearized reading' (duration: 1.971007035s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299747799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:42:29.054342Z","caller":"traceutil/trace.go:171","msg":"trace[1316598899] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5431; }","duration":"1.12465442s","start":"2026-05-21T19:42:27.929658Z","end":"2026-05-21T19:42:29.054313Z","steps":["trace[1316598899] 'agreement among raft nodes before linearized reading' (duration: 1.124435294s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643479357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce11b33c-034b-4983-bc7d-b64c5ae9d3bc\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-21T19:42:29.054388Z","caller":"traceutil/trace.go:171","msg":"trace[1434511999] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5431; }","duration":"1.299857151s","start":"2026-05-21T19:42:27.754516Z","end":"2026-05-21T19:42:29.054374Z","steps":["trace[1434511999] 'agreement among raft nodes before linearized reading' (duration: 1.299780699s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.083038Z","time spent":"1.971327233s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:42:29.054432Z","caller":"traceutil/trace.go:171","msg":"trace[1568668611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce11b33c-034b-4983-bc7d-b64c5ae9d3bc; range_end:; response_count:1; response_revision:5431; }","duration":"1.643606909s","start":"2026-05-21T19:42:27.41081Z","end":"2026-05-21T19:42:29.054417Z","steps":["trace[1568668611] 'agreement among raft nodes before linearized reading' (duration: 1.643321602s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.754486Z","time spent":"1.299938174s","remote":"127.0.0.1:53680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:42:29.05451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967581221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:42:29.054515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.410778Z","time spent":"1.643694642s","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce11b33c-034b-4983-bc7d-b64c5ae9d3bc\" "} {"level":"warn","ts":"2026-05-21T19:42:29.05455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.566207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:42:29.054566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.489327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j5dnx\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-05-21T19:42:29.054384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.071147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:42:29.05432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.091848Z","time spent":"1.962454565s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:42:29.054619Z","caller":"traceutil/trace.go:171","msg":"trace[1974656918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j5dnx; range_end:; response_count:1; response_revision:5431; }","duration":"822.596869ms","start":"2026-05-21T19:42:28.232007Z","end":"2026-05-21T19:42:29.054604Z","steps":["trace[1974656918] 'agreement among raft nodes before linearized reading' (duration: 822.441265ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.054636Z","caller":"traceutil/trace.go:171","msg":"trace[968196143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5431; }","duration":"554.406885ms","start":"2026-05-21T19:42:28.500216Z","end":"2026-05-21T19:42:29.054622Z","steps":["trace[968196143] 'agreement among raft nodes before linearized reading' (duration: 554.056327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:28.231983Z","time spent":"822.670201ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-j5dnx\" "} {"level":"warn","ts":"2026-05-21T19:42:29.054668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.748025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:42:29.054706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967618312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-21T19:42:29.054724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967926759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-21T19:42:29.054749Z","caller":"traceutil/trace.go:171","msg":"trace[1347346913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5431; }","duration":"1.967781106s","start":"2026-05-21T19:42:27.086957Z","end":"2026-05-21T19:42:29.054738Z","steps":["trace[1347346913] 'agreement among raft nodes before linearized reading' (duration: 1.967660403s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382705864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13286"} {"level":"info","ts":"2026-05-21T19:42:29.054779Z","caller":"traceutil/trace.go:171","msg":"trace[90616119] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5431; }","duration":"1.968007072s","start":"2026-05-21T19:42:27.086757Z","end":"2026-05-21T19:42:29.054764Z","steps":["trace[90616119] 'agreement among raft nodes before linearized reading' (duration: 1.967825477s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.086915Z","time spent":"1.967872458s","remote":"127.0.0.1:53632","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-21T19:42:29.054806Z","caller":"traceutil/trace.go:171","msg":"trace[1272355840] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5431; }","duration":"1.3833078s","start":"2026-05-21T19:42:27.671486Z","end":"2026-05-21T19:42:29.054794Z","steps":["trace[1272355840] 'agreement among raft nodes before linearized reading' (duration: 1.382594672s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.05482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.086745Z","time spent":"1.968064453s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:42:29.054613Z","caller":"traceutil/trace.go:171","msg":"trace[100845359] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5431; }","duration":"380.65764ms","start":"2026-05-21T19:42:28.673932Z","end":"2026-05-21T19:42:29.05459Z","steps":["trace[100845359] 'agreement among raft nodes before linearized reading' (duration: 380.538377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.929601Z","time spent":"1.124784334s","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-21T19:42:29.054851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.671455Z","time spent":"1.383383332s","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13309,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-21T19:42:29.054873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:28.673908Z","time spent":"380.949577ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":21,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:42:29.054674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:28.500189Z","time spent":"554.474336ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:42:29.054562Z","caller":"traceutil/trace.go:171","msg":"trace[1792280735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5431; }","duration":"1.967684804s","start":"2026-05-21T19:42:27.086863Z","end":"2026-05-21T19:42:29.054548Z","steps":["trace[1792280735] 'agreement among raft nodes before linearized reading' (duration: 1.967514149s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.084402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:42:29.054937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.086526594s","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-21T19:42:29.054999Z","caller":"traceutil/trace.go:171","msg":"trace[1883198635] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5431; }","duration":"883.194094ms","start":"2026-05-21T19:42:28.17179Z","end":"2026-05-21T19:42:29.054984Z","steps":["trace[1883198635] 'agreement among raft nodes before linearized reading' (duration: 883.01714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.054995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:27.086836Z","time spent":"1.968119094s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:42:29.055046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:28.171765Z","time spent":"883.267926ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":58,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:42:29.054737Z","caller":"traceutil/trace.go:171","msg":"trace[1205569313] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:5431; }","duration":"546.783866ms","start":"2026-05-21T19:42:28.507911Z","end":"2026-05-21T19:42:29.054695Z","steps":["trace[1205569313] 'agreement among raft nodes before linearized reading' (duration: 546.733915ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.05509Z","caller":"traceutil/trace.go:171","msg":"trace[1198583826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5431; }","duration":"2.086687648s","start":"2026-05-21T19:42:26.968336Z","end":"2026-05-21T19:42:29.055024Z","steps":["trace[1198583826] 'agreement among raft nodes before linearized reading' (duration: 2.086378351s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.055127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:28.50785Z","time spent":"547.262309ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:42:29.055159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:26.968322Z","time spent":"2.086821582s","remote":"127.0.0.1:53632","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-21T19:42:29.212919Z","caller":"traceutil/trace.go:171","msg":"trace[445804720] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"152.38046ms","start":"2026-05-21T19:42:29.060507Z","end":"2026-05-21T19:42:29.212887Z","steps":["trace[445804720] 'process raft request' (duration: 152.137774ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.431371Z","caller":"traceutil/trace.go:171","msg":"trace[1274975674] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"370.317823ms","start":"2026-05-21T19:42:29.061014Z","end":"2026-05-21T19:42:29.431332Z","steps":["trace[1274975674] 'process raft request' (duration: 370.077868ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.43138Z","caller":"traceutil/trace.go:171","msg":"trace[1540386498] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"370.199011ms","start":"2026-05-21T19:42:29.061151Z","end":"2026-05-21T19:42:29.43135Z","steps":["trace[1540386498] 'process raft request' (duration: 370.107769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.431569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.061113Z","time spent":"370.357185ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:29.431603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.060943Z","time spent":"370.514068ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:29.431798Z","caller":"traceutil/trace.go:171","msg":"trace[1864017058] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"370.026297ms","start":"2026-05-21T19:42:29.061724Z","end":"2026-05-21T19:42:29.43175Z","steps":["trace[1864017058] 'process raft request' (duration: 369.801251ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:29.431879Z","caller":"traceutil/trace.go:171","msg":"trace[1812966403] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"369.974855ms","start":"2026-05-21T19:42:29.061851Z","end":"2026-05-21T19:42:29.431826Z","steps":["trace[1812966403] 'process raft request' (duration: 369.808021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.06171Z","time spent":"370.206521ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:29.432053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.061843Z","time spent":"370.119909ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:29.432072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.454733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-21T19:42:29.432127Z","caller":"traceutil/trace.go:171","msg":"trace[1962151885] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5439; }","duration":"369.511994ms","start":"2026-05-21T19:42:29.0626Z","end":"2026-05-21T19:42:29.432112Z","steps":["trace[1962151885] 'agreement among raft nodes before linearized reading' (duration: 369.292989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.062582Z","time spent":"369.578746ms","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-21T19:42:29.431857Z","caller":"traceutil/trace.go:171","msg":"trace[1771337813] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5637; }","duration":"369.210256ms","start":"2026-05-21T19:42:29.062618Z","end":"2026-05-21T19:42:29.431828Z","steps":["trace[1771337813] 'read index received' (duration: 150.211495ms)","trace[1771337813] 'applied index is now lower than readState.Index' (duration: 218.997531ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:29.432213Z","caller":"traceutil/trace.go:171","msg":"trace[352679226] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"370.251193ms","start":"2026-05-21T19:42:29.06195Z","end":"2026-05-21T19:42:29.432201Z","steps":["trace[352679226] 'process raft request' (duration: 369.759241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.061944Z","time spent":"370.296513ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:29.432359Z","caller":"traceutil/trace.go:171","msg":"trace[1530612976] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"361.6414ms","start":"2026-05-21T19:42:29.070704Z","end":"2026-05-21T19:42:29.432346Z","steps":["trace[1530612976] 'process raft request' (duration: 361.052226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.726634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:42:29.432419Z","caller":"traceutil/trace.go:171","msg":"trace[985248540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5439; }","duration":"368.792715ms","start":"2026-05-21T19:42:29.063611Z","end":"2026-05-21T19:42:29.432404Z","steps":["trace[985248540] 'agreement among raft nodes before linearized reading' (duration: 368.704553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.070691Z","time spent":"361.705181ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13858,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:29.432468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.063603Z","time spent":"368.856138ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:42:29.432424Z","caller":"traceutil/trace.go:171","msg":"trace[759750319] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"370.664022ms","start":"2026-05-21T19:42:29.061712Z","end":"2026-05-21T19:42:29.432376Z","steps":["trace[759750319] 'process raft request' (duration: 369.600166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:29.432668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:29.061688Z","time spent":"370.870997ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:35.092894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.140431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:42:35.093326Z","caller":"traceutil/trace.go:171","msg":"trace[134921701] linearizableReadLoop","detail":"{readStateIndex:5696; appliedIndex:5695; }","duration":"426.52398ms","start":"2026-05-21T19:42:34.66678Z","end":"2026-05-21T19:42:35.093303Z","steps":["trace[134921701] 'read index received' (duration: 267.814486ms)","trace[134921701] 'applied index is now lower than readState.Index' (duration: 158.707884ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:42:35.093409Z","caller":"traceutil/trace.go:171","msg":"trace[128447125] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"570.818299ms","start":"2026-05-21T19:42:34.522515Z","end":"2026-05-21T19:42:35.093334Z","steps":["trace[128447125] 'process raft request' (duration: 412.090984ms)","trace[128447125] 'compare' (duration: 158.010038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:42:35.093532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.371517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:42:35.09362Z","caller":"traceutil/trace.go:171","msg":"trace[255574281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5489; }","duration":"169.520951ms","start":"2026-05-21T19:42:34.924078Z","end":"2026-05-21T19:42:35.093599Z","steps":["trace[255574281] 'agreement among raft nodes before linearized reading' (duration: 169.374027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:35.093635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:34.522508Z","time spent":"571.007584ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:35.093665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.809937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:42:35.093806Z","caller":"traceutil/trace.go:171","msg":"trace[501206766] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5489; }","duration":"427.052453ms","start":"2026-05-21T19:42:34.66673Z","end":"2026-05-21T19:42:35.093783Z","steps":["trace[501206766] 'agreement among raft nodes before linearized reading' (duration: 426.682774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:35.09394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:34.666705Z","time spent":"427.191356ms","remote":"127.0.0.1:58852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:42:35.094115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.18692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-05-21T19:42:35.094164Z","caller":"traceutil/trace.go:171","msg":"trace[383562876] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5489; }","duration":"104.288112ms","start":"2026-05-21T19:42:34.989861Z","end":"2026-05-21T19:42:35.094149Z","steps":["trace[383562876] 'agreement among raft nodes before linearized reading' (duration: 103.970804ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:39.812714Z","caller":"traceutil/trace.go:171","msg":"trace[1491252706] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"320.6466ms","start":"2026-05-21T19:42:39.492041Z","end":"2026-05-21T19:42:39.812687Z","steps":["trace[1491252706] 'process raft request' (duration: 320.503786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:39.812906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.492024Z","time spent":"320.781092ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:39.826373Z","caller":"traceutil/trace.go:171","msg":"trace[1027918219] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"332.4677ms","start":"2026-05-21T19:42:39.493842Z","end":"2026-05-21T19:42:39.82631Z","steps":["trace[1027918219] 'process raft request' (duration: 332.394228ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:39.826494Z","caller":"traceutil/trace.go:171","msg":"trace[1425952355] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"333.050075ms","start":"2026-05-21T19:42:39.493428Z","end":"2026-05-21T19:42:39.826478Z","steps":["trace[1425952355] 'process raft request' (duration: 332.761897ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:42:39.826527Z","caller":"traceutil/trace.go:171","msg":"trace[801149229] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"332.360448ms","start":"2026-05-21T19:42:39.494148Z","end":"2026-05-21T19:42:39.826508Z","steps":["trace[801149229] 'process raft request' (duration: 332.126881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:39.826586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.493831Z","time spent":"332.651695ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:39.826618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.494139Z","time spent":"332.4312ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:42:39.82668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.493417Z","time spent":"333.138297ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:39.826391Z","caller":"traceutil/trace.go:171","msg":"trace[1678031009] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"333.043144ms","start":"2026-05-21T19:42:39.493308Z","end":"2026-05-21T19:42:39.826351Z","steps":["trace[1678031009] 'process raft request' (duration: 332.750967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:39.826781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.493285Z","time spent":"333.465395ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:39.826838Z","caller":"traceutil/trace.go:171","msg":"trace[804545504] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"332.261325ms","start":"2026-05-21T19:42:39.494566Z","end":"2026-05-21T19:42:39.826828Z","steps":["trace[804545504] 'process raft request' (duration: 331.885086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:42:39.826902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:42:39.494527Z","time spent":"332.344857ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:42:59.802512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2755} {"level":"info","ts":"2026-05-21T19:42:59.87141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2755,"took":"67.32438ms","hash":2329024458,"current-db-size-bytes":55443456,"current-db-size":"55 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T19:42:59.871483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2329024458,"revision":2755,"compact-revision":-1} {"level":"info","ts":"2026-05-21T19:47:59.811978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5659} {"level":"info","ts":"2026-05-21T19:47:59.896221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5659,"took":"82.22561ms","hash":1344839007,"current-db-size-bytes":55443456,"current-db-size":"55 MB","current-db-size-in-use-bytes":21852160,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-21T19:47:59.896311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1344839007,"revision":5659,"compact-revision":2755} {"level":"info","ts":"2026-05-21T19:50:58.291045Z","caller":"traceutil/trace.go:171","msg":"trace[1419692283] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"144.149637ms","start":"2026-05-21T19:50:58.146839Z","end":"2026-05-21T19:50:58.290989Z","steps":["trace[1419692283] 'process raft request' (duration: 143.895171ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:51:33.140337Z","caller":"traceutil/trace.go:171","msg":"trace[755821652] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"138.16809ms","start":"2026-05-21T19:51:33.002142Z","end":"2026-05-21T19:51:33.140311Z","steps":["trace[755821652] 'process raft request' (duration: 108.702695ms)","trace[755821652] 'compare' (duration: 29.307071ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:51:33.140598Z","caller":"traceutil/trace.go:171","msg":"trace[345869084] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"138.14748ms","start":"2026-05-21T19:51:33.002441Z","end":"2026-05-21T19:51:33.140588Z","steps":["trace[345869084] 'process raft request' (duration: 137.826972ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:51:33.140898Z","caller":"traceutil/trace.go:171","msg":"trace[362359772] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"137.895934ms","start":"2026-05-21T19:51:33.00299Z","end":"2026-05-21T19:51:33.140886Z","steps":["trace[362359772] 'process raft request' (duration: 137.552515ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:51:33.141009Z","caller":"traceutil/trace.go:171","msg":"trace[1386967483] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"137.883343ms","start":"2026-05-21T19:51:33.003078Z","end":"2026-05-21T19:51:33.140961Z","steps":["trace[1386967483] 'process raft request' (duration: 137.690018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:51:33.141261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.625967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:51:33.14128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.701153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-05-21T19:51:33.141336Z","caller":"traceutil/trace.go:171","msg":"trace[914859958] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:8587; }","duration":"138.781785ms","start":"2026-05-21T19:51:33.002538Z","end":"2026-05-21T19:51:33.14132Z","steps":["trace[914859958] 'agreement among raft nodes before linearized reading' (duration: 138.649752ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:51:33.141341Z","caller":"traceutil/trace.go:171","msg":"trace[1484672508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8587; }","duration":"137.752839ms","start":"2026-05-21T19:51:33.003568Z","end":"2026-05-21T19:51:33.141321Z","steps":["trace[1484672508] 'agreement among raft nodes before linearized reading' (duration: 137.563115ms)"],"step_count":1}