{"level":"warn","ts":"2026-06-13T22:42:57.857315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:42:57.857416Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-13T22:42:57.857508Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:42:57.857529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-13T22:42:57.85755Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T22:42:57.858317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-13T22:42:57.858393Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-13T22:42:57.861598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.000246ms"} {"level":"info","ts":"2026-06-13T22:42:57.870475Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-13T22:42:57.870636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:42:57.870668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-13T22:42:57.87068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:42:57.870697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-13T22:42:57.870737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-13T22:42:57.875685Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:42:57.877528Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:42:57.878587Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-13T22:42:57.880622Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:42:57.88126Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:42:57.883199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-13T22:42:57.882732Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:42:57.884045Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:42:57.884058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:42:57.886477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-13T22:42:57.887246Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T22:42:57.887345Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-13T22:42:57.887368Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-13T22:42:57.888089Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:42:57.888114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:42:57.97096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:42:57.971055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:42:57.971095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-13T22:42:57.97112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:42:57.971132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-13T22:42:57.971153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-13T22:42:57.971203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-13T22:42:57.972142Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:42:57.972142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:42:57.972322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:42:57.973027Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:42:57.973135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:42:57.97317Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:42:57.974089Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:42:57.974148Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:42:57.976766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T22:42:57.977134Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-13T22:42:57.981871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"warn","ts":"2026-06-13T22:43:10.008493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.208144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"warn","ts":"2026-06-13T22:43:10.008592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.403267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-13T22:43:10.008504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.396397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:43:10.008715Z","caller":"traceutil/trace.go:171","msg":"trace[700748873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"157.59042ms","start":"2026-06-13T22:43:09.851094Z","end":"2026-06-13T22:43:10.008685Z","steps":["trace[700748873] 'range keys from in-memory index tree' (duration: 157.274557ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:10.008736Z","caller":"traceutil/trace.go:171","msg":"trace[1387597395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:351; }","duration":"129.711261ms","start":"2026-06-13T22:43:09.879003Z","end":"2026-06-13T22:43:10.008714Z","steps":["trace[1387597395] 'range keys from in-memory index tree' (duration: 129.333037ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:10.008627Z","caller":"traceutil/trace.go:171","msg":"trace[1709607987] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"225.422127ms","start":"2026-06-13T22:43:09.783182Z","end":"2026-06-13T22:43:10.008604Z","steps":["trace[1709607987] 'range keys from in-memory index tree' (duration: 225.118804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:10.008546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.50909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-06-13T22:43:10.009013Z","caller":"traceutil/trace.go:171","msg":"trace[195007057] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:351; }","duration":"132.024376ms","start":"2026-06-13T22:43:09.87696Z","end":"2026-06-13T22:43:10.008984Z","steps":["trace[195007057] 'range keys from in-memory index tree' (duration: 131.40326ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:31.974597Z","caller":"traceutil/trace.go:171","msg":"trace[441605252] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"132.351206ms","start":"2026-06-13T22:43:31.842222Z","end":"2026-06-13T22:43:31.974574Z","steps":["trace[441605252] 'process raft request' (duration: 132.225195ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:32.935862Z","caller":"traceutil/trace.go:171","msg":"trace[336271953] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"146.898445ms","start":"2026-06-13T22:43:32.788936Z","end":"2026-06-13T22:43:32.935835Z","steps":["trace[336271953] 'process raft request' (duration: 143.891807ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:34.619631Z","caller":"traceutil/trace.go:171","msg":"trace[1140458019] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"330.801824ms","start":"2026-06-13T22:43:34.288798Z","end":"2026-06-13T22:43:34.6196Z","steps":["trace[1140458019] 'process raft request' (duration: 330.621112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:34.620881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:34.288781Z","time spent":"330.984666ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:43:40.457518Z","caller":"traceutil/trace.go:171","msg":"trace[122520498] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"141.134768ms","start":"2026-06-13T22:43:40.316336Z","end":"2026-06-13T22:43:40.457471Z","steps":["trace[122520498] 'process raft request' (duration: 139.339398ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:40.791347Z","caller":"traceutil/trace.go:171","msg":"trace[1880343121] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"272.518039ms","start":"2026-06-13T22:43:40.518809Z","end":"2026-06-13T22:43:40.791327Z","steps":["trace[1880343121] 'process raft request' (duration: 272.429323ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:40.791366Z","caller":"traceutil/trace.go:171","msg":"trace[10108853] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"146.020717ms","start":"2026-06-13T22:43:40.64531Z","end":"2026-06-13T22:43:40.791331Z","steps":["trace[10108853] 'read index received' (duration: 145.997375ms)","trace[10108853] 'applied index is now lower than readState.Index' (duration: 20.612µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:43:40.791694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.362568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:43:40.791746Z","caller":"traceutil/trace.go:171","msg":"trace[1563894827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"146.467584ms","start":"2026-06-13T22:43:40.645261Z","end":"2026-06-13T22:43:40.791729Z","steps":["trace[1563894827] 'agreement among raft nodes before linearized reading' (duration: 146.221029ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:41.105568Z","caller":"traceutil/trace.go:171","msg":"trace[272745851] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"309.22925ms","start":"2026-06-13T22:43:40.796311Z","end":"2026-06-13T22:43:41.10554Z","steps":["trace[272745851] 'process raft request' (duration: 309.151335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:41.105698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:40.796283Z","time spent":"309.350397ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:43:41.10581Z","caller":"traceutil/trace.go:171","msg":"trace[865727589] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"447.993262ms","start":"2026-06-13T22:43:40.657806Z","end":"2026-06-13T22:43:41.105799Z","steps":["trace[865727589] 'process raft request' (duration: 419.886472ms)","trace[865727589] 'compare' (duration: 27.502573ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:43:41.10586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:40.657789Z","time spent":"448.041646ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:43:41.105912Z","caller":"traceutil/trace.go:171","msg":"trace[278705987] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"314.405626ms","start":"2026-06-13T22:43:40.791447Z","end":"2026-06-13T22:43:41.105853Z","steps":["trace[278705987] 'read index received' (duration: 286.254964ms)","trace[278705987] 'applied index is now lower than readState.Index' (duration: 28.142011ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:43:41.106154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.056348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:43:41.10623Z","caller":"traceutil/trace.go:171","msg":"trace[279093461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"458.167134ms","start":"2026-06-13T22:43:40.64804Z","end":"2026-06-13T22:43:41.106207Z","steps":["trace[279093461] 'agreement among raft nodes before linearized reading' (duration: 457.955122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:41.106242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.701761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:43:41.106268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:40.648Z","time spent":"458.259381ms","remote":"127.0.0.1:40182","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-06-13T22:43:41.106317Z","caller":"traceutil/trace.go:171","msg":"trace[1872362221] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:589; }","duration":"303.875222ms","start":"2026-06-13T22:43:40.802421Z","end":"2026-06-13T22:43:41.106296Z","steps":["trace[1872362221] 'agreement among raft nodes before linearized reading' (duration: 303.654618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:41.10637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:40.802372Z","time spent":"303.980468ms","remote":"127.0.0.1:40380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-06-13T22:43:41.106432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.601109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dlnwh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-13T22:43:41.106467Z","caller":"traceutil/trace.go:171","msg":"trace[1025352854] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dlnwh; range_end:; response_count:1; response_revision:589; }","duration":"148.667703ms","start":"2026-06-13T22:43:40.95779Z","end":"2026-06-13T22:43:41.106458Z","steps":["trace[1025352854] 'agreement among raft nodes before linearized reading' (duration: 148.576337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.182111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103733749863248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:43:42.266596Z","caller":"traceutil/trace.go:171","msg":"trace[1901601102] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"585.351309ms","start":"2026-06-13T22:43:41.681222Z","end":"2026-06-13T22:43:42.266573Z","steps":["trace[1901601102] 'read index received' (duration: 584.650437ms)","trace[1901601102] 'applied index is now lower than readState.Index' (duration: 700.082µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:43:42.266692Z","caller":"traceutil/trace.go:171","msg":"trace[1973108249] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"739.109635ms","start":"2026-06-13T22:43:41.527549Z","end":"2026-06-13T22:43:42.266659Z","steps":["trace[1973108249] 'process raft request' (duration: 738.574763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.266872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.755068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-13T22:43:42.266914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.646406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:43:42.266919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:41.527516Z","time spent":"739.294246ms","remote":"127.0.0.1:39992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:43:42.266945Z","caller":"traceutil/trace.go:171","msg":"trace[1019153247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"585.754172ms","start":"2026-06-13T22:43:41.681181Z","end":"2026-06-13T22:43:42.266935Z","steps":["trace[1019153247] 'agreement among raft nodes before linearized reading' (duration: 585.513028ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:42.266942Z","caller":"traceutil/trace.go:171","msg":"trace[1660903636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"248.869235ms","start":"2026-06-13T22:43:42.018055Z","end":"2026-06-13T22:43:42.266924Z","steps":["trace[1660903636] 'agreement among raft nodes before linearized reading' (duration: 248.682713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.266973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:41.681164Z","time spent":"585.803665ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T22:43:43.229175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.143311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:43:43.229242Z","caller":"traceutil/trace.go:171","msg":"trace[181069399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"119.257488ms","start":"2026-06-13T22:43:43.109965Z","end":"2026-06-13T22:43:43.229222Z","steps":["trace[181069399] 'range keys from in-memory index tree' (duration: 118.996562ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:44.229127Z","caller":"traceutil/trace.go:171","msg":"trace[153711913] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"130.429665ms","start":"2026-06-13T22:43:44.098674Z","end":"2026-06-13T22:43:44.229103Z","steps":["trace[153711913] 'process raft request' (duration: 130.308098ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:58.506902Z","caller":"traceutil/trace.go:171","msg":"trace[989019491] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"161.992918ms","start":"2026-06-13T22:43:58.344891Z","end":"2026-06-13T22:43:58.506884Z","steps":["trace[989019491] 'read index received' (duration: 161.785478ms)","trace[989019491] 'applied index is now lower than readState.Index' (duration: 206.78µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:43:58.506998Z","caller":"traceutil/trace.go:171","msg":"trace[607438381] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"441.624528ms","start":"2026-06-13T22:43:58.065367Z","end":"2026-06-13T22:43:58.506991Z","steps":["trace[607438381] 'process raft request' (duration: 441.395106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:58.507082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:58.065333Z","time spent":"441.684011ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:43:58.507212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.322315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-13T22:43:58.507284Z","caller":"traceutil/trace.go:171","msg":"trace[1810994223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"162.434829ms","start":"2026-06-13T22:43:58.344836Z","end":"2026-06-13T22:43:58.507271Z","steps":["trace[1810994223] 'agreement among raft nodes before linearized reading' (duration: 162.24553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:58.507314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.934608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-13T22:43:58.507439Z","caller":"traceutil/trace.go:171","msg":"trace[1805428180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"155.100246ms","start":"2026-06-13T22:43:58.352313Z","end":"2026-06-13T22:43:58.507414Z","steps":["trace[1805428180] 'agreement among raft nodes before linearized reading' (duration: 154.723908ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:44:32.662205Z","caller":"traceutil/trace.go:171","msg":"trace[1534478052] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"622.332045ms","start":"2026-06-13T22:44:32.039849Z","end":"2026-06-13T22:44:32.662181Z","steps":["trace[1534478052] 'process raft request' (duration: 622.156118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:44:32.662369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:44:32.039832Z","time spent":"622.465159ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:44:51.51662Z","caller":"traceutil/trace.go:171","msg":"trace[874072985] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"107.704953ms","start":"2026-06-13T22:44:51.408893Z","end":"2026-06-13T22:44:51.516598Z","steps":["trace[874072985] 'read index received' (duration: 107.561089ms)","trace[874072985] 'applied index is now lower than readState.Index' (duration: 143.074µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:44:51.516678Z","caller":"traceutil/trace.go:171","msg":"trace[664373489] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"129.723837ms","start":"2026-06-13T22:44:51.386939Z","end":"2026-06-13T22:44:51.516663Z","steps":["trace[664373489] 'process raft request' (duration: 129.530931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:44:51.51675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.839357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:44:51.516838Z","caller":"traceutil/trace.go:171","msg":"trace[908033165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1321; }","duration":"107.976861ms","start":"2026-06-13T22:44:51.408848Z","end":"2026-06-13T22:44:51.516825Z","steps":["trace[908033165] 'agreement among raft nodes before linearized reading' (duration: 107.852637ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:44:53.562504Z","caller":"traceutil/trace.go:171","msg":"trace[274950296] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"245.007025ms","start":"2026-06-13T22:44:53.317474Z","end":"2026-06-13T22:44:53.562481Z","steps":["trace[274950296] 'process raft request' (duration: 244.83586ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:44:58.964853Z","caller":"traceutil/trace.go:171","msg":"trace[285833162] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"135.58948ms","start":"2026-06-13T22:44:58.829227Z","end":"2026-06-13T22:44:58.964816Z","steps":["trace[285833162] 'process raft request' (duration: 135.425655ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:17.303641Z","caller":"traceutil/trace.go:171","msg":"trace[419396842] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"166.493117ms","start":"2026-06-13T22:45:17.137111Z","end":"2026-06-13T22:45:17.303604Z","steps":["trace[419396842] 'process raft request' (duration: 165.950844ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:17.488465Z","caller":"traceutil/trace.go:171","msg":"trace[925884807] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"348.049006ms","start":"2026-06-13T22:45:17.140392Z","end":"2026-06-13T22:45:17.488441Z","steps":["trace[925884807] 'process raft request' (duration: 343.24169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:17.488601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:17.140363Z","time spent":"348.164369ms","remote":"127.0.0.1:40182","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-06-13T22:45:25.03603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.414447ms","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-06-13T22:45:25.036207Z","caller":"traceutil/trace.go:171","msg":"trace[1404597] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1858; }","duration":"586.664793ms","start":"2026-06-13T22:45:24.449518Z","end":"2026-06-13T22:45:25.036183Z","steps":["trace[1404597] 'count revisions from in-memory index tree' (duration: 586.312765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.036248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.44949Z","time spent":"586.747476ms","remote":"127.0.0.1:51934","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-06-13T22:45:25.036117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.043376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44df0aa7-20c5-444d-be41-352a70da1d25\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-13T22:45:25.036438Z","caller":"traceutil/trace.go:171","msg":"trace[1862333163] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44df0aa7-20c5-444d-be41-352a70da1d25; range_end:; response_count:1; response_revision:1858; }","duration":"575.418585ms","start":"2026-06-13T22:45:24.460987Z","end":"2026-06-13T22:45:25.036405Z","steps":["trace[1862333163] 'range keys from in-memory index tree' (duration: 574.846752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.036521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.46098Z","time spent":"575.524848ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44df0aa7-20c5-444d-be41-352a70da1d25\" "} {"level":"warn","ts":"2026-06-13T22:45:25.184339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103733749865735,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:45:25.323042Z","caller":"traceutil/trace.go:171","msg":"trace[780077497] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"816.634789ms","start":"2026-06-13T22:45:24.506347Z","end":"2026-06-13T22:45:25.322982Z","steps":["trace[780077497] 'process raft request' (duration: 816.341342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.323667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.506331Z","time spent":"817.007377ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:45:25.603604Z","caller":"traceutil/trace.go:171","msg":"trace[106055522] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"920.2481ms","start":"2026-06-13T22:45:24.683332Z","end":"2026-06-13T22:45:25.603581Z","steps":["trace[106055522] 'read index received' (duration: 639.596849ms)","trace[106055522] 'applied index is now lower than readState.Index' (duration: 280.650471ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:45:25.603665Z","caller":"traceutil/trace.go:171","msg":"trace[22317320] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"561.434775ms","start":"2026-06-13T22:45:25.042197Z","end":"2026-06-13T22:45:25.603632Z","steps":["trace[22317320] 'process raft request' (duration: 561.244311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.603723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.389023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:45:25.603747Z","caller":"traceutil/trace.go:171","msg":"trace[245615801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"920.529137ms","start":"2026-06-13T22:45:24.683211Z","end":"2026-06-13T22:45:25.60374Z","steps":["trace[245615801] 'agreement among raft nodes before linearized reading' (duration: 920.471345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.603768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.68318Z","time spent":"920.583837ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T22:45:25.603793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:25.042153Z","time spent":"561.568318ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:45:25.620859Z","caller":"traceutil/trace.go:171","msg":"trace[1320123193] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"264.129664ms","start":"2026-06-13T22:45:25.356703Z","end":"2026-06-13T22:45:25.620832Z","steps":["trace[1320123193] 'process raft request' (duration: 263.856158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.620977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.789608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-13T22:45:25.621188Z","caller":"traceutil/trace.go:171","msg":"trace[376972803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1861; }","duration":"275.062104ms","start":"2026-06-13T22:45:25.34611Z","end":"2026-06-13T22:45:25.621172Z","steps":["trace[376972803] 'agreement among raft nodes before linearized reading' (duration: 274.689086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.621003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.49236ms","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-06-13T22:45:25.62129Z","caller":"traceutil/trace.go:171","msg":"trace[1317329427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"446.856478ms","start":"2026-06-13T22:45:25.17442Z","end":"2026-06-13T22:45:25.621277Z","steps":["trace[1317329427] 'agreement among raft nodes before linearized reading' (duration: 446.373097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.621335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:25.174398Z","time spent":"446.914879ms","remote":"127.0.0.1:48976","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-06-13T22:45:25.620978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.417177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-13T22:45:25.621296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.373385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-13T22:45:25.621403Z","caller":"traceutil/trace.go:171","msg":"trace[464848513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"101.939569ms","start":"2026-06-13T22:45:25.519447Z","end":"2026-06-13T22:45:25.621386Z","steps":["trace[464848513] 'agreement among raft nodes before linearized reading' (duration: 101.392696ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:25.621431Z","caller":"traceutil/trace.go:171","msg":"trace[1112527946] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1861; }","duration":"761.568599ms","start":"2026-06-13T22:45:24.859835Z","end":"2026-06-13T22:45:25.621404Z","steps":["trace[1112527946] 'agreement among raft nodes before linearized reading' (duration: 761.196061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.621113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.544146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44df0aa7-20c5-444d-be41-352a70da1d25\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-06-13T22:45:25.621492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.859819Z","time spent":"761.662732ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-13T22:45:25.621502Z","caller":"traceutil/trace.go:171","msg":"trace[874195231] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44df0aa7-20c5-444d-be41-352a70da1d25; range_end:; response_count:1; response_revision:1861; }","duration":"703.962305ms","start":"2026-06-13T22:45:24.917523Z","end":"2026-06-13T22:45:25.621485Z","steps":["trace[874195231] 'agreement among raft nodes before linearized reading' (duration: 703.530755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:25.621551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:24.917491Z","time spent":"704.037627ms","remote":"127.0.0.1:40102","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-44df0aa7-20c5-444d-be41-352a70da1d25\" "} {"level":"warn","ts":"2026-06-13T22:45:26.017072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.731908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:45:26.017158Z","caller":"traceutil/trace.go:171","msg":"trace[1766973511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"331.881701ms","start":"2026-06-13T22:45:25.685257Z","end":"2026-06-13T22:45:26.017139Z","steps":["trace[1766973511] 'range keys from in-memory index tree' (duration: 331.638905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:26.017227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:25.685241Z","time spent":"331.952012ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T22:45:26.017477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.763877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:45:26.017577Z","caller":"traceutil/trace.go:171","msg":"trace[1273932721] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"283.034945ms","start":"2026-06-13T22:45:25.734526Z","end":"2026-06-13T22:45:26.017561Z","steps":["trace[1273932721] 'read index received' (duration: 10.847118ms)","trace[1273932721] 'applied index is now lower than readState.Index' (duration: 272.186677ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:45:26.017675Z","caller":"traceutil/trace.go:171","msg":"trace[1082561438] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"313.655635ms","start":"2026-06-13T22:45:25.704008Z","end":"2026-06-13T22:45:26.017663Z","steps":["trace[1082561438] 'process raft request' (duration: 41.437827ms)","trace[1082561438] 'compare' (duration: 271.516332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:26.01774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:25.703978Z","time spent":"313.728676ms","remote":"127.0.0.1:40182","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-06-13T22:45:26.018056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.526176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:45:26.018115Z","caller":"traceutil/trace.go:171","msg":"trace[459320653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"283.591168ms","start":"2026-06-13T22:45:25.73451Z","end":"2026-06-13T22:45:26.018101Z","steps":["trace[459320653] 'agreement among raft nodes before linearized reading' (duration: 283.435254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:26.01849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.436551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:45:26.018672Z","caller":"traceutil/trace.go:171","msg":"trace[1219017285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"282.618496ms","start":"2026-06-13T22:45:25.736042Z","end":"2026-06-13T22:45:26.018661Z","steps":["trace[1219017285] 'agreement among raft nodes before linearized reading' (duration: 281.737456ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:38.685542Z","caller":"traceutil/trace.go:171","msg":"trace[994801076] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"321.948394ms","start":"2026-06-13T22:45:38.363561Z","end":"2026-06-13T22:45:38.68551Z","steps":["trace[994801076] 'process raft request' (duration: 321.76239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:38.685759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:38.363537Z","time spent":"322.109838ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:45:38.687107Z","caller":"traceutil/trace.go:171","msg":"trace[162762061] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2054; }","duration":"311.192166ms","start":"2026-06-13T22:45:38.375888Z","end":"2026-06-13T22:45:38.687081Z","steps":["trace[162762061] 'read index received' (duration: 309.46765ms)","trace[162762061] 'applied index is now lower than readState.Index' (duration: 1.723626ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:38.687423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.511203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-06-13T22:45:38.687486Z","caller":"traceutil/trace.go:171","msg":"trace[447158104] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1977; }","duration":"311.631076ms","start":"2026-06-13T22:45:38.375845Z","end":"2026-06-13T22:45:38.687476Z","steps":["trace[447158104] 'agreement among raft nodes before linearized reading' (duration: 311.420381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:38.687518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:38.375829Z","time spent":"311.681536ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12011,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-13T22:45:38.687533Z","caller":"traceutil/trace.go:171","msg":"trace[1839357548] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"318.054772ms","start":"2026-06-13T22:45:38.369149Z","end":"2026-06-13T22:45:38.687204Z","steps":["trace[1839357548] 'process raft request' (duration: 317.816957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:38.687729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:38.36911Z","time spent":"318.513622ms","remote":"127.0.0.1:40182","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-06-13T22:45:58.978559Z","caller":"traceutil/trace.go:171","msg":"trace[1026436183] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"153.513932ms","start":"2026-06-13T22:45:58.825022Z","end":"2026-06-13T22:45:58.978536Z","steps":["trace[1026436183] 'read index received' (duration: 153.315718ms)","trace[1026436183] 'applied index is now lower than readState.Index' (duration: 197.214µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:58.978717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:45:58.978759Z","caller":"traceutil/trace.go:171","msg":"trace[445817526] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2100; }","duration":"153.759617ms","start":"2026-06-13T22:45:58.824988Z","end":"2026-06-13T22:45:58.978748Z","steps":["trace[445817526] 'agreement among raft nodes before linearized reading' (duration: 153.681186ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:58.978612Z","caller":"traceutil/trace.go:171","msg":"trace[1022730443] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"155.387938ms","start":"2026-06-13T22:45:58.823188Z","end":"2026-06-13T22:45:58.978576Z","steps":["trace[1022730443] 'process raft request' (duration: 155.215495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:58.979037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.0396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:45:58.97909Z","caller":"traceutil/trace.go:171","msg":"trace[421972599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2100; }","duration":"109.156553ms","start":"2026-06-13T22:45:58.86992Z","end":"2026-06-13T22:45:58.979076Z","steps":["trace[421972599] 'agreement among raft nodes before linearized reading' (duration: 108.949209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:58.97931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.618792ms","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-06-13T22:45:58.979403Z","caller":"traceutil/trace.go:171","msg":"trace[812751968] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"143.746785ms","start":"2026-06-13T22:45:58.835635Z","end":"2026-06-13T22:45:58.979381Z","steps":["trace[812751968] 'agreement among raft nodes before linearized reading' (duration: 143.092883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:59.59279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.053995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:45:59.593146Z","caller":"traceutil/trace.go:171","msg":"trace[2007315521] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"608.476603ms","start":"2026-06-13T22:45:58.98465Z","end":"2026-06-13T22:45:59.593127Z","steps":["trace[2007315521] 'process raft request' (duration: 459.984069ms)","trace[2007315521] 'compare' (duration: 147.886082ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:59.593229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:58.984629Z","time spent":"608.569154ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:45:59.5933Z","caller":"traceutil/trace.go:171","msg":"trace[864016849] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"604.650609ms","start":"2026-06-13T22:45:58.988642Z","end":"2026-06-13T22:45:59.593292Z","steps":["trace[864016849] 'read index received' (duration: 456.115855ms)","trace[864016849] 'applied index is now lower than readState.Index' (duration: 148.533884ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:45:59.593439Z","caller":"traceutil/trace.go:171","msg":"trace[1930067179] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"312.100096ms","start":"2026-06-13T22:45:59.281332Z","end":"2026-06-13T22:45:59.593433Z","steps":["trace[1930067179] 'process raft request' (duration: 311.561426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:59.593476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:59.281293Z","time spent":"312.166897ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:45:59.593617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.241428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130298"} {"level":"info","ts":"2026-06-13T22:45:59.593723Z","caller":"traceutil/trace.go:171","msg":"trace[255887142] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2103; }","duration":"105.391261ms","start":"2026-06-13T22:45:59.488314Z","end":"2026-06-13T22:45:59.593705Z","steps":["trace[255887142] 'agreement among raft nodes before linearized reading' (duration: 105.143416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:59.593683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.045287ms","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-06-13T22:45:59.594424Z","caller":"traceutil/trace.go:171","msg":"trace[825714592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"605.786611ms","start":"2026-06-13T22:45:58.988609Z","end":"2026-06-13T22:45:59.594395Z","steps":["trace[825714592] 'agreement among raft nodes before linearized reading' (duration: 604.994376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:59.594549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:58.988592Z","time spent":"605.937884ms","remote":"127.0.0.1:48976","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-06-13T22:45:59.593827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.18602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-13T22:45:59.594821Z","caller":"traceutil/trace.go:171","msg":"trace[1011661304] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2103; }","duration":"103.22313ms","start":"2026-06-13T22:45:59.491583Z","end":"2026-06-13T22:45:59.594806Z","steps":["trace[1011661304] 'agreement among raft nodes before linearized reading' (duration: 102.15455ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:00.228298Z","caller":"traceutil/trace.go:171","msg":"trace[2098823830] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"303.312618ms","start":"2026-06-13T22:45:59.924941Z","end":"2026-06-13T22:46:00.228254Z","steps":["trace[2098823830] 'process raft request' (duration: 276.348836ms)","trace[2098823830] 'compare' (duration: 26.539174ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:00.228539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:59.924921Z","time spent":"303.520613ms","remote":"127.0.0.1:40182","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-06-13T22:46:00.228758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.735713ms","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-06-13T22:46:00.228366Z","caller":"traceutil/trace.go:171","msg":"trace[857460771] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2187; }","duration":"242.330105ms","start":"2026-06-13T22:45:59.986015Z","end":"2026-06-13T22:46:00.228345Z","steps":["trace[857460771] 'read index received' (duration: 215.288681ms)","trace[857460771] 'applied index is now lower than readState.Index' (duration: 27.040554ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:00.228798Z","caller":"traceutil/trace.go:171","msg":"trace[1407428925] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"242.829265ms","start":"2026-06-13T22:45:59.985959Z","end":"2026-06-13T22:46:00.228788Z","steps":["trace[1407428925] 'agreement among raft nodes before linearized reading' (duration: 242.450347ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:00.228384Z","caller":"traceutil/trace.go:171","msg":"trace[1334590919] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"303.152605ms","start":"2026-06-13T22:45:59.925206Z","end":"2026-06-13T22:46:00.228358Z","steps":["trace[1334590919] 'process raft request' (duration: 302.801059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:00.229096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:59.92519Z","time spent":"303.820309ms","remote":"127.0.0.1:40182","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-06-13T22:46:01.371989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.669062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:46:01.372109Z","caller":"traceutil/trace.go:171","msg":"trace[145241249] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"689.725354ms","start":"2026-06-13T22:46:00.682363Z","end":"2026-06-13T22:46:01.372088Z","steps":["trace[145241249] 'read index received' (duration: 35.61µs)","trace[145241249] 'applied index is now lower than readState.Index' (duration: 689.687844ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:01.372385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.015349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:46:01.372462Z","caller":"traceutil/trace.go:171","msg":"trace[1340004164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"690.126061ms","start":"2026-06-13T22:46:00.682322Z","end":"2026-06-13T22:46:01.372448Z","steps":["trace[1340004164] 'agreement among raft nodes before linearized reading' (duration: 689.961068ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:01.372473Z","caller":"traceutil/trace.go:171","msg":"trace[404937560] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"1.138837497s","start":"2026-06-13T22:46:00.233585Z","end":"2026-06-13T22:46:01.372422Z","steps":["trace[404937560] 'process raft request' (duration: 378.645925ms)","trace[404937560] 'compare' (duration: 759.54564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:01.372524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:00.682304Z","time spent":"690.211863ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T22:46:01.372615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:00.233552Z","time spent":"1.139006869s","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:46:01.385532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.388007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-13T22:46:01.385583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.677925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-13T22:46:01.385605Z","caller":"traceutil/trace.go:171","msg":"trace[2035750648] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2110; }","duration":"418.484179ms","start":"2026-06-13T22:46:00.96709Z","end":"2026-06-13T22:46:01.385574Z","steps":["trace[2035750648] 'agreement among raft nodes before linearized reading' (duration: 418.402297ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:01.385623Z","caller":"traceutil/trace.go:171","msg":"trace[1099656627] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:2110; }","duration":"302.752187ms","start":"2026-06-13T22:46:01.08286Z","end":"2026-06-13T22:46:01.385612Z","steps":["trace[1099656627] 'agreement among raft nodes before linearized reading' (duration: 302.623515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:01.385642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:00.967066Z","time spent":"418.566461ms","remote":"127.0.0.1:59770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:46:01.385633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.754273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-13T22:46:01.385653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:01.082843Z","time spent":"302.803578ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-06-13T22:46:01.385688Z","caller":"traceutil/trace.go:171","msg":"trace[2047484117] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2110; }","duration":"649.861005ms","start":"2026-06-13T22:46:00.735815Z","end":"2026-06-13T22:46:01.385676Z","steps":["trace[2047484117] 'agreement among raft nodes before linearized reading' (duration: 649.766964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:01.385751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:00.735797Z","time spent":"649.944957ms","remote":"127.0.0.1:40282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:46:01.385846Z","caller":"traceutil/trace.go:171","msg":"trace[187314195] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"402.345822ms","start":"2026-06-13T22:46:00.983486Z","end":"2026-06-13T22:46:01.385832Z","steps":["trace[187314195] 'process raft request' (duration: 401.881794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:01.385923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:00.983467Z","time spent":"402.412833ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:46:01.386053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.787133ms","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-06-13T22:46:01.386124Z","caller":"traceutil/trace.go:171","msg":"trace[1582403611] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"149.900106ms","start":"2026-06-13T22:46:01.236208Z","end":"2026-06-13T22:46:01.386108Z","steps":["trace[1582403611] 'agreement among raft nodes before linearized reading' (duration: 149.720402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:01.704467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.745482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:46:01.704545Z","caller":"traceutil/trace.go:171","msg":"trace[861920332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2111; }","duration":"104.867273ms","start":"2026-06-13T22:46:01.599657Z","end":"2026-06-13T22:46:01.704525Z","steps":["trace[861920332] 'range keys from in-memory index tree' (duration: 104.572228ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:01.809262Z","caller":"traceutil/trace.go:171","msg":"trace[1764012092] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"209.415411ms","start":"2026-06-13T22:46:01.599795Z","end":"2026-06-13T22:46:01.809211Z","steps":["trace[1764012092] 'process raft request' (duration: 209.207978ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:01.810005Z","caller":"traceutil/trace.go:171","msg":"trace[1382741670] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"100.203196ms","start":"2026-06-13T22:46:01.709772Z","end":"2026-06-13T22:46:01.809975Z","steps":["trace[1382741670] 'process raft request' (duration: 100.011692ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:54.656065Z","caller":"traceutil/trace.go:171","msg":"trace[343511190] linearizableReadLoop","detail":"{readStateIndex:2544; appliedIndex:2543; }","duration":"119.855088ms","start":"2026-06-13T22:46:54.536172Z","end":"2026-06-13T22:46:54.656027Z","steps":["trace[343511190] 'read index received' (duration: 88.427395ms)","trace[343511190] 'applied index is now lower than readState.Index' (duration: 31.425553ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:54.656579Z","caller":"traceutil/trace.go:171","msg":"trace[409122889] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"134.031995ms","start":"2026-06-13T22:46:54.52253Z","end":"2026-06-13T22:46:54.656562Z","steps":["trace[409122889] 'process raft request' (duration: 102.006522ms)","trace[409122889] 'compare' (duration: 30.923335ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:54.656587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.389667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:46:54.656666Z","caller":"traceutil/trace.go:171","msg":"trace[1539144193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2448; }","duration":"120.510408ms","start":"2026-06-13T22:46:54.536135Z","end":"2026-06-13T22:46:54.656645Z","steps":["trace[1539144193] 'agreement among raft nodes before linearized reading' (duration: 120.251944ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:03.863466Z","caller":"traceutil/trace.go:171","msg":"trace[1462353320] linearizableReadLoop","detail":"{readStateIndex:2647; appliedIndex:2646; }","duration":"180.568866ms","start":"2026-06-13T22:47:03.682855Z","end":"2026-06-13T22:47:03.863424Z","steps":["trace[1462353320] 'read index received' (duration: 180.332952ms)","trace[1462353320] 'applied index is now lower than readState.Index' (duration: 234.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:03.863509Z","caller":"traceutil/trace.go:171","msg":"trace[568060701] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"224.840622ms","start":"2026-06-13T22:47:03.63863Z","end":"2026-06-13T22:47:03.86347Z","steps":["trace[568060701] 'process raft request' (duration: 224.575277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:03.863701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.816089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:47:03.863738Z","caller":"traceutil/trace.go:171","msg":"trace[1311847137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2549; }","duration":"180.934722ms","start":"2026-06-13T22:47:03.682792Z","end":"2026-06-13T22:47:03.863727Z","steps":["trace[1311847137] 'agreement among raft nodes before linearized reading' (duration: 180.82512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:03.863888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.578224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:47:03.863972Z","caller":"traceutil/trace.go:171","msg":"trace[2109584930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2549; }","duration":"162.697645ms","start":"2026-06-13T22:47:03.701256Z","end":"2026-06-13T22:47:03.863954Z","steps":["trace[2109584930] 'agreement among raft nodes before linearized reading' (duration: 162.488452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:03.864242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.130013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-06-13T22:47:03.864299Z","caller":"traceutil/trace.go:171","msg":"trace[381246915] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2549; }","duration":"107.261975ms","start":"2026-06-13T22:47:03.757021Z","end":"2026-06-13T22:47:03.864283Z","steps":["trace[381246915] 'agreement among raft nodes before linearized reading' (duration: 107.043852ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:36.111911Z","caller":"traceutil/trace.go:171","msg":"trace[1205855645] linearizableReadLoop","detail":"{readStateIndex:2832; appliedIndex:2831; }","duration":"318.982134ms","start":"2026-06-13T22:47:35.792902Z","end":"2026-06-13T22:47:36.111884Z","steps":["trace[1205855645] 'read index received' (duration: 318.839712ms)","trace[1205855645] 'applied index is now lower than readState.Index' (duration: 141.252µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:47:36.112134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.208107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:47:36.111949Z","caller":"traceutil/trace.go:171","msg":"trace[1941414812] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"347.647871ms","start":"2026-06-13T22:47:35.76427Z","end":"2026-06-13T22:47:36.111918Z","steps":["trace[1941414812] 'process raft request' (duration: 347.471779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:36.112356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:47:35.764233Z","time spent":"348.050868ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:47:36.112211Z","caller":"traceutil/trace.go:171","msg":"trace[1432709407] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2727; }","duration":"319.300458ms","start":"2026-06-13T22:47:35.792892Z","end":"2026-06-13T22:47:36.112193Z","steps":["trace[1432709407] 'agreement among raft nodes before linearized reading' (duration: 319.142306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:36.112701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:47:35.792817Z","time spent":"319.865157ms","remote":"127.0.0.1:40068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:47:36.11811Z","caller":"traceutil/trace.go:171","msg":"trace[651335264] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"136.341705ms","start":"2026-06-13T22:47:35.981748Z","end":"2026-06-13T22:47:36.118089Z","steps":["trace[651335264] 'process raft request' (duration: 136.148312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:36.425209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.965931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:47:36.425749Z","caller":"traceutil/trace.go:171","msg":"trace[1698927336] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"223.89391ms","start":"2026-06-13T22:47:36.201806Z","end":"2026-06-13T22:47:36.4257Z","steps":["trace[1698927336] 'compare' (duration: 215.85896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:36.579591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.14555ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-13T22:47:36.579752Z","caller":"traceutil/trace.go:171","msg":"trace[231406506] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2834; }","duration":"260.650199ms","start":"2026-06-13T22:47:36.319073Z","end":"2026-06-13T22:47:36.579724Z","steps":["trace[231406506] 'read index received' (duration: 100.104634ms)","trace[231406506] 'applied index is now lower than readState.Index' (duration: 160.543585ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:47:36.580043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.885411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-13T22:47:36.580451Z","caller":"traceutil/trace.go:171","msg":"trace[1597546268] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2730; }","duration":"226.337877ms","start":"2026-06-13T22:47:36.354102Z","end":"2026-06-13T22:47:36.58044Z","steps":["trace[1597546268] 'agreement among raft nodes before linearized reading' (duration: 225.807849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:36.58003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.936213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:47:36.580623Z","caller":"traceutil/trace.go:171","msg":"trace[198789055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2730; }","duration":"261.584763ms","start":"2026-06-13T22:47:36.319017Z","end":"2026-06-13T22:47:36.580602Z","steps":["trace[198789055] 'agreement among raft nodes before linearized reading' (duration: 260.768441ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:36.748273Z","caller":"traceutil/trace.go:171","msg":"trace[904506127] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"161.871166ms","start":"2026-06-13T22:47:36.586364Z","end":"2026-06-13T22:47:36.748235Z","steps":["trace[904506127] 'process raft request' (duration: 161.633452ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:36.749414Z","caller":"traceutil/trace.go:171","msg":"trace[1306403129] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"145.905467ms","start":"2026-06-13T22:47:36.603452Z","end":"2026-06-13T22:47:36.749358Z","steps":["trace[1306403129] 'process raft request' (duration: 145.718575ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:36.749874Z","caller":"traceutil/trace.go:171","msg":"trace[1332138229] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"162.143049ms","start":"2026-06-13T22:47:36.587674Z","end":"2026-06-13T22:47:36.749817Z","steps":["trace[1332138229] 'process raft request' (duration: 161.311657ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:36.750205Z","caller":"traceutil/trace.go:171","msg":"trace[1201401058] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"162.425464ms","start":"2026-06-13T22:47:36.587763Z","end":"2026-06-13T22:47:36.750189Z","steps":["trace[1201401058] 'process raft request' (duration: 161.375928ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:17.029009Z","caller":"traceutil/trace.go:171","msg":"trace[165131863] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"118.211368ms","start":"2026-06-13T22:51:16.910737Z","end":"2026-06-13T22:51:17.028948Z","steps":["trace[165131863] 'process raft request' (duration: 114.139496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:18.353232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.103177ms","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":"warn","ts":"2026-06-13T22:51:18.353235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.361138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:51:18.353302Z","caller":"traceutil/trace.go:171","msg":"trace[1458989177] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4427; }","duration":"244.224691ms","start":"2026-06-13T22:51:18.109062Z","end":"2026-06-13T22:51:18.353286Z","steps":["trace[1458989177] 'range keys from in-memory index tree' (duration: 243.928162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:18.352953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.231212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-8rvz8\" ","response":"range_response_count:1 size:4331"} {"level":"info","ts":"2026-06-13T22:51:18.353353Z","caller":"traceutil/trace.go:171","msg":"trace[689689161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4427; }","duration":"289.517103ms","start":"2026-06-13T22:51:18.063808Z","end":"2026-06-13T22:51:18.353325Z","steps":["trace[689689161] 'range keys from in-memory index tree' (duration: 289.161563ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:18.353427Z","caller":"traceutil/trace.go:171","msg":"trace[313556578] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-8rvz8; range_end:; response_count:1; response_revision:4427; }","duration":"161.746167ms","start":"2026-06-13T22:51:18.19166Z","end":"2026-06-13T22:51:18.353406Z","steps":["trace[313556578] 'range keys from in-memory index tree' (duration: 161.074629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.08582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.018031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:20.085949Z","caller":"traceutil/trace.go:171","msg":"trace[1597823769] linearizableReadLoop","detail":"{readStateIndex:4683; appliedIndex:4682; }","duration":"276.023541ms","start":"2026-06-13T22:51:19.809905Z","end":"2026-06-13T22:51:20.085928Z","steps":["trace[1597823769] 'read index received' (duration: 139.734655ms)","trace[1597823769] 'applied index is now lower than readState.Index' (duration: 136.286396ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:20.086086Z","caller":"traceutil/trace.go:171","msg":"trace[409799678] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"319.131481ms","start":"2026-06-13T22:51:19.766903Z","end":"2026-06-13T22:51:20.086035Z","steps":["trace[409799678] 'process raft request' (duration: 182.711542ms)","trace[409799678] 'compare' (duration: 134.763843ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:20.086205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:19.766888Z","time spent":"319.261604ms","remote":"127.0.0.1:40182","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-06-13T22:51:20.086224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.034393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:20.086363Z","caller":"traceutil/trace.go:171","msg":"trace[1300942384] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4509; }","duration":"222.229368ms","start":"2026-06-13T22:51:19.864114Z","end":"2026-06-13T22:51:20.086343Z","steps":["trace[1300942384] 'agreement among raft nodes before linearized reading' (duration: 222.000402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.086407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.829052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-13T22:51:20.086514Z","caller":"traceutil/trace.go:171","msg":"trace[954095803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4509; }","duration":"124.999706ms","start":"2026-06-13T22:51:19.961492Z","end":"2026-06-13T22:51:20.086492Z","steps":["trace[954095803] 'agreement among raft nodes before linearized reading' (duration: 124.676237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.086564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.322943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9a4b8aa-675c-403a-9eaf-7b6ac16965f4\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-13T22:51:20.086615Z","caller":"traceutil/trace.go:171","msg":"trace[980970109] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9a4b8aa-675c-403a-9eaf-7b6ac16965f4; range_end:; response_count:1; response_revision:4509; }","duration":"202.401165ms","start":"2026-06-13T22:51:19.884197Z","end":"2026-06-13T22:51:20.086598Z","steps":["trace[980970109] 'agreement among raft nodes before linearized reading' (duration: 202.187519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.086673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.755855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-13T22:51:20.086792Z","caller":"traceutil/trace.go:171","msg":"trace[691214304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4509; }","duration":"179.947241ms","start":"2026-06-13T22:51:19.906825Z","end":"2026-06-13T22:51:20.086772Z","steps":["trace[691214304] 'agreement among raft nodes before linearized reading' (duration: 179.56953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.087045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.138321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9424"} {"level":"warn","ts":"2026-06-13T22:51:20.087046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.156765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:51:20.087095Z","caller":"traceutil/trace.go:171","msg":"trace[1750190220] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4509; }","duration":"277.219353ms","start":"2026-06-13T22:51:19.809864Z","end":"2026-06-13T22:51:20.087083Z","steps":["trace[1750190220] 'agreement among raft nodes before linearized reading' (duration: 277.048818ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:20.08711Z","caller":"traceutil/trace.go:171","msg":"trace[1217992675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4509; }","duration":"131.224226ms","start":"2026-06-13T22:51:19.955869Z","end":"2026-06-13T22:51:20.087094Z","steps":["trace[1217992675] 'agreement among raft nodes before linearized reading' (duration: 131.012391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.08762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.401272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-13T22:51:20.087709Z","caller":"traceutil/trace.go:171","msg":"trace[1506444244] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4509; }","duration":"104.536446ms","start":"2026-06-13T22:51:19.983155Z","end":"2026-06-13T22:51:20.087691Z","steps":["trace[1506444244] 'agreement among raft nodes before linearized reading' (duration: 103.512158ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:20.611624Z","caller":"traceutil/trace.go:171","msg":"trace[2047049852] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"241.217887ms","start":"2026-06-13T22:51:20.370376Z","end":"2026-06-13T22:51:20.611594Z","steps":["trace[2047049852] 'process raft request' (duration: 240.935429ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:20.671265Z","caller":"traceutil/trace.go:171","msg":"trace[236877616] linearizableReadLoop","detail":"{readStateIndex:4696; appliedIndex:4694; }","duration":"197.060121ms","start":"2026-06-13T22:51:20.474181Z","end":"2026-06-13T22:51:20.671241Z","steps":["trace[236877616] 'read index received' (duration: 137.301319ms)","trace[236877616] 'applied index is now lower than readState.Index' (duration: 59.739731ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:20.671307Z","caller":"traceutil/trace.go:171","msg":"trace[1881321411] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"298.788618ms","start":"2026-06-13T22:51:20.372475Z","end":"2026-06-13T22:51:20.671264Z","steps":["trace[1881321411] 'process raft request' (duration: 298.613624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:20.672364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.168081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-13T22:51:20.672432Z","caller":"traceutil/trace.go:171","msg":"trace[216356462] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4522; }","duration":"198.273244ms","start":"2026-06-13T22:51:20.47414Z","end":"2026-06-13T22:51:20.672413Z","steps":["trace[216356462] 'agreement among raft nodes before linearized reading' (duration: 197.209855ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:21.515718Z","caller":"traceutil/trace.go:171","msg":"trace[1554519923] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"224.65632ms","start":"2026-06-13T22:51:21.291039Z","end":"2026-06-13T22:51:21.515695Z","steps":["trace[1554519923] 'process raft request' (duration: 163.014661ms)","trace[1554519923] 'compare' (duration: 61.432763ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:21.553423Z","caller":"traceutil/trace.go:171","msg":"trace[1186476252] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4702; }","duration":"203.835413ms","start":"2026-06-13T22:51:21.349568Z","end":"2026-06-13T22:51:21.553404Z","steps":["trace[1186476252] 'read index received' (duration: 104.510737ms)","trace[1186476252] 'applied index is now lower than readState.Index' (duration: 99.323756ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:21.553484Z","caller":"traceutil/trace.go:171","msg":"trace[909520425] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"244.363907ms","start":"2026-06-13T22:51:21.309084Z","end":"2026-06-13T22:51:21.553448Z","steps":["trace[909520425] 'process raft request' (duration: 244.188273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:21.553674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.0933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f9fb37e-7c75-4a95-8cb4-1092c733ac2e\" ","response":"range_response_count:1 size:4049"} {"level":"info","ts":"2026-06-13T22:51:21.553726Z","caller":"traceutil/trace.go:171","msg":"trace[1595987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f9fb37e-7c75-4a95-8cb4-1092c733ac2e; range_end:; response_count:1; response_revision:4529; }","duration":"204.194773ms","start":"2026-06-13T22:51:21.349519Z","end":"2026-06-13T22:51:21.553714Z","steps":["trace[1595987] 'agreement among raft nodes before linearized reading' (duration: 203.993667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:21.55389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.139385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:51:21.554011Z","caller":"traceutil/trace.go:171","msg":"trace[574813924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4529; }","duration":"159.30066ms","start":"2026-06-13T22:51:21.39468Z","end":"2026-06-13T22:51:21.553981Z","steps":["trace[574813924] 'agreement among raft nodes before linearized reading' (duration: 159.024412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:21.554015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.352669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:21.554075Z","caller":"traceutil/trace.go:171","msg":"trace[456883093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4529; }","duration":"149.456801ms","start":"2026-06-13T22:51:21.404603Z","end":"2026-06-13T22:51:21.554059Z","steps":["trace[456883093] 'agreement among raft nodes before linearized reading' (duration: 149.344508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.119772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.770905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:22.119928Z","caller":"traceutil/trace.go:171","msg":"trace[1905526301] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"249.134439ms","start":"2026-06-13T22:51:21.870779Z","end":"2026-06-13T22:51:22.119913Z","steps":["trace[1905526301] 'process raft request' (duration: 249.071198ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.120039Z","caller":"traceutil/trace.go:171","msg":"trace[416310275] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4704; }","duration":"546.464778ms","start":"2026-06-13T22:51:21.573563Z","end":"2026-06-13T22:51:22.120028Z","steps":["trace[416310275] 'read index received' (duration: 433.492118ms)","trace[416310275] 'applied index is now lower than readState.Index' (duration: 112.97145ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:22.120105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.53851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:22.12013Z","caller":"traceutil/trace.go:171","msg":"trace[2082333705] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4531; }","duration":"546.567371ms","start":"2026-06-13T22:51:21.573555Z","end":"2026-06-13T22:51:22.120122Z","steps":["trace[2082333705] 'agreement among raft nodes before linearized reading' (duration: 546.5228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.120154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:21.573503Z","time spent":"546.642593ms","remote":"127.0.0.1:52016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:51:22.120226Z","caller":"traceutil/trace.go:171","msg":"trace[1053853555] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"561.721084ms","start":"2026-06-13T22:51:21.558283Z","end":"2026-06-13T22:51:22.120004Z","steps":["trace[1053853555] 'process raft request' (duration: 448.64171ms)","trace[1053853555] 'compare' (duration: 112.57844ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:22.120342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:21.558257Z","time spent":"562.024392ms","remote":"127.0.0.1:40182","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-06-13T22:51:22.19072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.346936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18b8c5bf97440c28\" ","response":"range_response_count:1 size:1179"} {"level":"warn","ts":"2026-06-13T22:51:22.190765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.795714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-13T22:51:22.190801Z","caller":"traceutil/trace.go:171","msg":"trace[290718865] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4532; }","duration":"270.870416ms","start":"2026-06-13T22:51:21.91992Z","end":"2026-06-13T22:51:22.190791Z","steps":["trace[290718865] 'agreement among raft nodes before linearized reading' (duration: 270.754873ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.190804Z","caller":"traceutil/trace.go:171","msg":"trace[1845184643] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18b8c5bf97440c28; range_end:; response_count:1; response_revision:4532; }","duration":"569.506541ms","start":"2026-06-13T22:51:21.621277Z","end":"2026-06-13T22:51:22.190784Z","steps":["trace[1845184643] 'agreement among raft nodes before linearized reading' (duration: 569.289825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.190785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.92103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:22.19087Z","caller":"traceutil/trace.go:171","msg":"trace[966944918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4532; }","duration":"509.055754ms","start":"2026-06-13T22:51:21.681791Z","end":"2026-06-13T22:51:22.190847Z","steps":["trace[966944918] 'agreement among raft nodes before linearized reading' (duration: 508.90741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.190848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:21.621254Z","time spent":"569.585322ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":1,"response size":1202,"request content":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18b8c5bf97440c28\" "} {"level":"warn","ts":"2026-06-13T22:51:22.190923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:21.68177Z","time spent":"509.142296ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T22:51:22.412181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.482615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:22.412318Z","caller":"traceutil/trace.go:171","msg":"trace[1639044956] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"217.802379ms","start":"2026-06-13T22:51:22.194494Z","end":"2026-06-13T22:51:22.412297Z","steps":["trace[1639044956] 'process raft request' (duration: 115.027946ms)","trace[1639044956] 'compare' (duration: 102.30136ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:22.451734Z","caller":"traceutil/trace.go:171","msg":"trace[1500930470] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"255.482303ms","start":"2026-06-13T22:51:22.196202Z","end":"2026-06-13T22:51:22.451685Z","steps":["trace[1500930470] 'process raft request' (duration: 255.248677ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.451824Z","caller":"traceutil/trace.go:171","msg":"trace[940593036] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"255.34995ms","start":"2026-06-13T22:51:22.196461Z","end":"2026-06-13T22:51:22.451811Z","steps":["trace[940593036] 'process raft request' (duration: 255.140994ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.451981Z","caller":"traceutil/trace.go:171","msg":"trace[1146472204] linearizableReadLoop","detail":"{readStateIndex:4712; appliedIndex:4707; }","duration":"209.850153ms","start":"2026-06-13T22:51:22.24211Z","end":"2026-06-13T22:51:22.45196Z","steps":["trace[1146472204] 'read index received' (duration: 67.492664ms)","trace[1146472204] 'applied index is now lower than readState.Index' (duration: 142.356199ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:22.452019Z","caller":"traceutil/trace.go:171","msg":"trace[174761186] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"255.309488ms","start":"2026-06-13T22:51:22.1967Z","end":"2026-06-13T22:51:22.452009Z","steps":["trace[174761186] 'process raft request' (duration: 255.064652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.452118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.001167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:51:22.452144Z","caller":"traceutil/trace.go:171","msg":"trace[1905445325] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4537; }","duration":"210.070939ms","start":"2026-06-13T22:51:22.242065Z","end":"2026-06-13T22:51:22.452136Z","steps":["trace[1905445325] 'agreement among raft nodes before linearized reading' (duration: 210.006107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:22.452175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.444907ms","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-06-13T22:51:22.452214Z","caller":"traceutil/trace.go:171","msg":"trace[1500392794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4537; }","duration":"115.52655ms","start":"2026-06-13T22:51:22.336675Z","end":"2026-06-13T22:51:22.452201Z","steps":["trace[1500392794] 'agreement among raft nodes before linearized reading' (duration: 115.451447ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.451692Z","caller":"traceutil/trace.go:171","msg":"trace[726658158] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"255.348809ms","start":"2026-06-13T22:51:22.196294Z","end":"2026-06-13T22:51:22.451643Z","steps":["trace[726658158] 'process raft request' (duration: 255.259917ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.603938Z","caller":"traceutil/trace.go:171","msg":"trace[1572585026] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"149.920744ms","start":"2026-06-13T22:51:22.453991Z","end":"2026-06-13T22:51:22.603912Z","steps":["trace[1572585026] 'process raft request' (duration: 98.260271ms)","trace[1572585026] 'compare' (duration: 51.55277ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:22.622582Z","caller":"traceutil/trace.go:171","msg":"trace[976599110] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4539; }","duration":"166.799243ms","start":"2026-06-13T22:51:22.455761Z","end":"2026-06-13T22:51:22.62256Z","steps":["trace[976599110] 'process raft request' (duration: 166.551556ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:22.622956Z","caller":"traceutil/trace.go:171","msg":"trace[1279756964] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"100.059129ms","start":"2026-06-13T22:51:22.52288Z","end":"2026-06-13T22:51:22.62294Z","steps":["trace[1279756964] 'process raft request' (duration: 99.611516ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:23.438512Z","caller":"traceutil/trace.go:171","msg":"trace[2107215418] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4571; }","duration":"150.547142ms","start":"2026-06-13T22:51:23.287943Z","end":"2026-06-13T22:51:23.43849Z","steps":["trace[2107215418] 'process raft request' (duration: 150.451539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:23.438702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.687056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-54glb-tls\" ","response":"range_response_count:1 size:1581"} {"level":"info","ts":"2026-06-13T22:51:23.438833Z","caller":"traceutil/trace.go:171","msg":"trace[850399444] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-54glb-tls; range_end:; response_count:1; response_revision:4571; }","duration":"150.841019ms","start":"2026-06-13T22:51:23.287968Z","end":"2026-06-13T22:51:23.438809Z","steps":["trace[850399444] 'agreement among raft nodes before linearized reading' (duration: 150.545051ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:23.472998Z","caller":"traceutil/trace.go:171","msg":"trace[905587862] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"104.821862ms","start":"2026-06-13T22:51:23.368126Z","end":"2026-06-13T22:51:23.472948Z","steps":["trace[905587862] 'process raft request' (duration: 104.511714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:23.473041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.211348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:23.473136Z","caller":"traceutil/trace.go:171","msg":"trace[341903489] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4573; }","duration":"103.355993ms","start":"2026-06-13T22:51:23.36976Z","end":"2026-06-13T22:51:23.473116Z","steps":["trace[341903489] 'agreement among raft nodes before linearized reading' (duration: 103.168867ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.190851Z","caller":"traceutil/trace.go:171","msg":"trace[68967333] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"433.515302ms","start":"2026-06-13T22:51:23.757291Z","end":"2026-06-13T22:51:24.190807Z","steps":["trace[68967333] 'process raft request' (duration: 433.387118ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.190926Z","caller":"traceutil/trace.go:171","msg":"trace[2042755073] linearizableReadLoop","detail":"{readStateIndex:4775; appliedIndex:4772; }","duration":"395.601134ms","start":"2026-06-13T22:51:23.795291Z","end":"2026-06-13T22:51:24.190893Z","steps":["trace[2042755073] 'read index received' (duration: 395.212893ms)","trace[2042755073] 'applied index is now lower than readState.Index' (duration: 386.391µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:24.191003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.757273Z","time spent":"433.661435ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:51:24.190849Z","caller":"traceutil/trace.go:171","msg":"trace[952798635] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"435.715661ms","start":"2026-06-13T22:51:23.755064Z","end":"2026-06-13T22:51:24.190779Z","steps":["trace[952798635] 'process raft request' (duration: 435.163316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.191276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.707223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-13T22:51:24.191324Z","caller":"traceutil/trace.go:171","msg":"trace[913739576] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4598; }","duration":"448.828666ms","start":"2026-06-13T22:51:23.74248Z","end":"2026-06-13T22:51:24.191309Z","steps":["trace[913739576] 'agreement among raft nodes before linearized reading' (duration: 448.569669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.191366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.742468Z","time spent":"448.889318ms","remote":"127.0.0.1:39992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-06-13T22:51:24.19108Z","caller":"traceutil/trace.go:171","msg":"trace[1689663521] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"426.142981ms","start":"2026-06-13T22:51:23.764878Z","end":"2026-06-13T22:51:24.191021Z","steps":["trace[1689663521] 'process raft request' (duration: 425.859404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.191525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.764859Z","time spent":"426.578933ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:51:24.191606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.755038Z","time spent":"436.249916ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":918,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:51:24.219509Z","caller":"traceutil/trace.go:171","msg":"trace[800786092] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"384.582263ms","start":"2026-06-13T22:51:23.834901Z","end":"2026-06-13T22:51:24.219483Z","steps":["trace[800786092] 'process raft request' (duration: 384.166272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.219676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:23.834882Z","time spent":"384.694646ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:51:24.221793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.193047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-13T22:51:24.221892Z","caller":"traceutil/trace.go:171","msg":"trace[1906014955] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4599; }","duration":"292.375093ms","start":"2026-06-13T22:51:23.929497Z","end":"2026-06-13T22:51:24.221872Z","steps":["trace[1906014955] 'agreement among raft nodes before linearized reading' (duration: 290.04846ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.339181Z","caller":"traceutil/trace.go:171","msg":"trace[1562740500] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"110.274632ms","start":"2026-06-13T22:51:24.228886Z","end":"2026-06-13T22:51:24.339161Z","steps":["trace[1562740500] 'process raft request' (duration: 54.472563ms)","trace[1562740500] 'compare' (duration: 55.699436ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:24.343265Z","caller":"traceutil/trace.go:171","msg":"trace[299513800] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"111.146497ms","start":"2026-06-13T22:51:24.232099Z","end":"2026-06-13T22:51:24.343245Z","steps":["trace[299513800] 'process raft request' (duration: 110.90108ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.343913Z","caller":"traceutil/trace.go:171","msg":"trace[1181931308] linearizableReadLoop","detail":"{readStateIndex:4780; appliedIndex:4778; }","duration":"111.523987ms","start":"2026-06-13T22:51:24.232354Z","end":"2026-06-13T22:51:24.343878Z","steps":["trace[1181931308] 'read index received' (duration: 51.561935ms)","trace[1181931308] 'applied index is now lower than readState.Index' (duration: 59.960522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:24.344166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.796674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-13T22:51:24.344191Z","caller":"traceutil/trace.go:171","msg":"trace[751409401] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4605; }","duration":"111.854865ms","start":"2026-06-13T22:51:24.232329Z","end":"2026-06-13T22:51:24.344184Z","steps":["trace[751409401] 'agreement among raft nodes before linearized reading' (duration: 111.66666ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.545632Z","caller":"traceutil/trace.go:171","msg":"trace[686104615] linearizableReadLoop","detail":"{readStateIndex:4783; appliedIndex:4782; }","duration":"201.644464ms","start":"2026-06-13T22:51:24.343957Z","end":"2026-06-13T22:51:24.545601Z","steps":["trace[686104615] 'read index received' (duration: 110.296333ms)","trace[686104615] 'applied index is now lower than readState.Index' (duration: 91.346321ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:24.545722Z","caller":"traceutil/trace.go:171","msg":"trace[382305191] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"258.142192ms","start":"2026-06-13T22:51:24.28754Z","end":"2026-06-13T22:51:24.545682Z","steps":["trace[382305191] 'process raft request' (duration: 166.688499ms)","trace[382305191] 'compare' (duration: 91.087763ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:24.546125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.868235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-06-13T22:51:24.54623Z","caller":"traceutil/trace.go:171","msg":"trace[34765194] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4606; }","duration":"206.970638ms","start":"2026-06-13T22:51:24.339196Z","end":"2026-06-13T22:51:24.546167Z","steps":["trace[34765194] 'agreement among raft nodes before linearized reading' (duration: 206.604898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.546715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.567174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9a4b8aa-675c-403a-9eaf-7b6ac16965f4\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:24.546854Z","caller":"traceutil/trace.go:171","msg":"trace[157366013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9a4b8aa-675c-403a-9eaf-7b6ac16965f4; range_end:; response_count:0; response_revision:4607; }","duration":"197.773629ms","start":"2026-06-13T22:51:24.34905Z","end":"2026-06-13T22:51:24.546824Z","steps":["trace[157366013] 'agreement among raft nodes before linearized reading' (duration: 197.557973ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.546957Z","caller":"traceutil/trace.go:171","msg":"trace[1741940072] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"188.851668ms","start":"2026-06-13T22:51:24.358094Z","end":"2026-06-13T22:51:24.546945Z","steps":["trace[1741940072] 'process raft request' (duration: 188.230142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.547112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.539046ms","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-06-13T22:51:24.548424Z","caller":"traceutil/trace.go:171","msg":"trace[1576905855] 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:4606; }","duration":"248.892283ms","start":"2026-06-13T22:51:24.299504Z","end":"2026-06-13T22:51:24.548396Z","steps":["trace[1576905855] 'agreement among raft nodes before linearized reading' (duration: 246.56847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:24.547272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.181665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:51:24.547486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.102777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-13T22:51:24.547462Z","caller":"traceutil/trace.go:171","msg":"trace[224532731] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4607; }","duration":"188.868419ms","start":"2026-06-13T22:51:24.358575Z","end":"2026-06-13T22:51:24.547444Z","steps":["trace[224532731] 'process raft request' (duration: 187.966124ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.56042Z","caller":"traceutil/trace.go:171","msg":"trace[866539986] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4607; }","duration":"186.367861ms","start":"2026-06-13T22:51:24.374028Z","end":"2026-06-13T22:51:24.560396Z","steps":["trace[866539986] 'agreement among raft nodes before linearized reading' (duration: 173.160274ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:24.560529Z","caller":"traceutil/trace.go:171","msg":"trace[1904971069] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4607; }","duration":"208.15395ms","start":"2026-06-13T22:51:24.352333Z","end":"2026-06-13T22:51:24.560487Z","steps":["trace[1904971069] 'agreement among raft nodes before linearized reading' (duration: 194.85972ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:28.537914Z","caller":"traceutil/trace.go:171","msg":"trace[1429881076] linearizableReadLoop","detail":"{readStateIndex:4843; appliedIndex:4842; }","duration":"195.254072ms","start":"2026-06-13T22:51:28.342643Z","end":"2026-06-13T22:51:28.537897Z","steps":["trace[1429881076] 'read index received' (duration: 195.104848ms)","trace[1429881076] 'applied index is now lower than readState.Index' (duration: 148.584µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:28.538264Z","caller":"traceutil/trace.go:171","msg":"trace[1403406558] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"218.504546ms","start":"2026-06-13T22:51:28.31975Z","end":"2026-06-13T22:51:28.538255Z","steps":["trace[1403406558] 'process raft request' (duration: 218.043473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:28.538641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.984122ms","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-06-13T22:51:28.538677Z","caller":"traceutil/trace.go:171","msg":"trace[348730477] 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:4664; }","duration":"196.064864ms","start":"2026-06-13T22:51:28.342601Z","end":"2026-06-13T22:51:28.538666Z","steps":["trace[348730477] 'agreement among raft nodes before linearized reading' (duration: 195.955021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:28.539072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.198026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:51:28.539124Z","caller":"traceutil/trace.go:171","msg":"trace[392349361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4664; }","duration":"184.287768ms","start":"2026-06-13T22:51:28.354829Z","end":"2026-06-13T22:51:28.539117Z","steps":["trace[392349361] 'agreement among raft nodes before linearized reading' (duration: 184.192005ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:29.780287Z","caller":"traceutil/trace.go:171","msg":"trace[1225536319] linearizableReadLoop","detail":"{readStateIndex:4861; appliedIndex:4860; }","duration":"404.852944ms","start":"2026-06-13T22:51:29.37541Z","end":"2026-06-13T22:51:29.780262Z","steps":["trace[1225536319] 'read index received' (duration: 3.656738ms)","trace[1225536319] 'applied index is now lower than readState.Index' (duration: 401.195216ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:29.780668Z","caller":"traceutil/trace.go:171","msg":"trace[485036686] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"439.326255ms","start":"2026-06-13T22:51:29.34129Z","end":"2026-06-13T22:51:29.780616Z","steps":["trace[485036686] 'process raft request' (duration: 438.821682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:29.780744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.313997ms","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-06-13T22:51:29.780786Z","caller":"traceutil/trace.go:171","msg":"trace[568659966] 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:4682; }","duration":"405.394568ms","start":"2026-06-13T22:51:29.375378Z","end":"2026-06-13T22:51:29.780773Z","steps":["trace[568659966] 'agreement among raft nodes before linearized reading' (duration: 405.246615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:29.780822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:29.375367Z","time spent":"405.446631ms","remote":"127.0.0.1:40068","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-06-13T22:51:29.780962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:29.341276Z","time spent":"439.511201ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16532,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:51:29.792281Z","caller":"traceutil/trace.go:171","msg":"trace[199340472] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"409.045085ms","start":"2026-06-13T22:51:29.383218Z","end":"2026-06-13T22:51:29.792263Z","steps":["trace[199340472] 'process raft request' (duration: 408.85671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:29.792397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:29.383209Z","time spent":"409.130328ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:51:29.792696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.204957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:29.792724Z","caller":"traceutil/trace.go:171","msg":"trace[1531040568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4684; }","duration":"110.277639ms","start":"2026-06-13T22:51:29.682439Z","end":"2026-06-13T22:51:29.792716Z","steps":["trace[1531040568] 'agreement among raft nodes before linearized reading' (duration: 110.224647ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:33.543984Z","caller":"traceutil/trace.go:171","msg":"trace[786679775] linearizableReadLoop","detail":"{readStateIndex:4942; appliedIndex:4941; }","duration":"436.251929ms","start":"2026-06-13T22:51:33.10771Z","end":"2026-06-13T22:51:33.543962Z","steps":["trace[786679775] 'read index received' (duration: 408.908594ms)","trace[786679775] 'applied index is now lower than readState.Index' (duration: 27.342365ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:33.544042Z","caller":"traceutil/trace.go:171","msg":"trace[31301234] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"451.984585ms","start":"2026-06-13T22:51:33.092025Z","end":"2026-06-13T22:51:33.54401Z","steps":["trace[31301234] 'process raft request' (duration: 424.64159ms)","trace[31301234] 'compare' (duration: 26.931364ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:33.544174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:33.091999Z","time spent":"452.102289ms","remote":"127.0.0.1:40182","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-06-13T22:51:33.54419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.245027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-06-13T22:51:33.544178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.750198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:51:33.544217Z","caller":"traceutil/trace.go:171","msg":"trace[1245833760] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363; range_end:; response_count:1; response_revision:4761; }","duration":"191.283528ms","start":"2026-06-13T22:51:33.352924Z","end":"2026-06-13T22:51:33.544207Z","steps":["trace[1245833760] 'agreement among raft nodes before linearized reading' (duration: 191.147165ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:33.544246Z","caller":"traceutil/trace.go:171","msg":"trace[1787750053] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4761; }","duration":"152.85429ms","start":"2026-06-13T22:51:33.391375Z","end":"2026-06-13T22:51:33.544229Z","steps":["trace[1787750053] 'agreement among raft nodes before linearized reading' (duration: 152.735527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:33.54426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.535596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-13T22:51:33.544339Z","caller":"traceutil/trace.go:171","msg":"trace[1406026810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363; range_end:; response_count:1; response_revision:4761; }","duration":"436.6883ms","start":"2026-06-13T22:51:33.107634Z","end":"2026-06-13T22:51:33.544323Z","steps":["trace[1406026810] 'agreement among raft nodes before linearized reading' (duration: 436.421473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:33.544377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:33.107592Z","time spent":"436.778973ms","remote":"127.0.0.1:40102","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-cd0cd195-686e-440f-85bc-7e294cf5b363\" "} {"level":"info","ts":"2026-06-13T22:51:33.783064Z","caller":"traceutil/trace.go:171","msg":"trace[977181851] linearizableReadLoop","detail":"{readStateIndex:4943; appliedIndex:4942; }","duration":"125.989798ms","start":"2026-06-13T22:51:33.65705Z","end":"2026-06-13T22:51:33.78304Z","steps":["trace[977181851] 'read index received' (duration: 125.755702ms)","trace[977181851] 'applied index is now lower than readState.Index' (duration: 233.406µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:33.783151Z","caller":"traceutil/trace.go:171","msg":"trace[773593106] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"232.88197ms","start":"2026-06-13T22:51:33.550226Z","end":"2026-06-13T22:51:33.783108Z","steps":["trace[773593106] 'process raft request' (duration: 232.667354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:33.783248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.154482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:33.783285Z","caller":"traceutil/trace.go:171","msg":"trace[272661069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4762; }","duration":"126.270565ms","start":"2026-06-13T22:51:33.657005Z","end":"2026-06-13T22:51:33.783275Z","steps":["trace[272661069] 'agreement among raft nodes before linearized reading' (duration: 126.162142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:33.783356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.825104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:33.783429Z","caller":"traceutil/trace.go:171","msg":"trace[1262845278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4762; }","duration":"102.945367ms","start":"2026-06-13T22:51:33.680465Z","end":"2026-06-13T22:51:33.783411Z","steps":["trace[1262845278] 'agreement among raft nodes before linearized reading' (duration: 102.818484ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:34.466273Z","caller":"traceutil/trace.go:171","msg":"trace[1078544428] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"297.349521ms","start":"2026-06-13T22:51:34.168904Z","end":"2026-06-13T22:51:34.466254Z","steps":["trace[1078544428] 'process raft request' (duration: 297.208097ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:34.497192Z","caller":"traceutil/trace.go:171","msg":"trace[1065517517] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"228.775347ms","start":"2026-06-13T22:51:34.268396Z","end":"2026-06-13T22:51:34.497171Z","steps":["trace[1065517517] 'process raft request' (duration: 228.651294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:35.729277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.339475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:35.729553Z","caller":"traceutil/trace.go:171","msg":"trace[1170591250] linearizableReadLoop","detail":"{readStateIndex:4962; appliedIndex:4960; }","duration":"306.567016ms","start":"2026-06-13T22:51:35.422968Z","end":"2026-06-13T22:51:35.729535Z","steps":["trace[1170591250] 'read index received' (duration: 141.903503ms)","trace[1170591250] 'applied index is now lower than readState.Index' (duration: 164.662203ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:35.729612Z","caller":"traceutil/trace.go:171","msg":"trace[1579390928] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"334.119432ms","start":"2026-06-13T22:51:35.395466Z","end":"2026-06-13T22:51:35.729585Z","steps":["trace[1579390928] 'process raft request' (duration: 333.927847ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:35.729645Z","caller":"traceutil/trace.go:171","msg":"trace[282520399] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"179.559535ms","start":"2026-06-13T22:51:35.550072Z","end":"2026-06-13T22:51:35.729631Z","steps":["trace[282520399] 'process raft request' (duration: 179.416502ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:35.729704Z","caller":"traceutil/trace.go:171","msg":"trace[935177631] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"335.94334ms","start":"2026-06-13T22:51:35.393728Z","end":"2026-06-13T22:51:35.729671Z","steps":["trace[935177631] 'process raft request' (duration: 171.129234ms)","trace[935177631] 'compare' (duration: 164.17363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:35.729749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:35.395456Z","time spent":"334.223096ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:51:35.729792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.816665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363\" ","response":"range_response_count:1 size:3948"} {"level":"warn","ts":"2026-06-13T22:51:35.729829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.835523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-13T22:51:35.729863Z","caller":"traceutil/trace.go:171","msg":"trace[2056953200] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd0cd195-686e-440f-85bc-7e294cf5b363; range_end:; response_count:1; response_revision:4782; }","duration":"137.927698ms","start":"2026-06-13T22:51:35.591917Z","end":"2026-06-13T22:51:35.729845Z","steps":["trace[2056953200] 'agreement among raft nodes before linearized reading' (duration: 137.724743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:35.729881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:35.393714Z","time spent":"336.062194ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1407,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:51:35.7299Z","caller":"traceutil/trace.go:171","msg":"trace[940533947] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4782; }","duration":"306.947746ms","start":"2026-06-13T22:51:35.422937Z","end":"2026-06-13T22:51:35.729885Z","steps":["trace[940533947] 'agreement among raft nodes before linearized reading' (duration: 306.692439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:35.729942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:35.422926Z","time spent":"307.008977ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-13T22:51:36.812053Z","caller":"traceutil/trace.go:171","msg":"trace[1841301774] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4965; }","duration":"220.36031ms","start":"2026-06-13T22:51:36.591677Z","end":"2026-06-13T22:51:36.812037Z","steps":["trace[1841301774] 'read index received' (duration: 218.398549ms)","trace[1841301774] 'applied index is now lower than readState.Index' (duration: 1.960881ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:36.812171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.475973ms","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":"info","ts":"2026-06-13T22:51:36.812143Z","caller":"traceutil/trace.go:171","msg":"trace[622577998] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"308.120709ms","start":"2026-06-13T22:51:36.503997Z","end":"2026-06-13T22:51:36.812118Z","steps":["trace[622577998] 'process raft request' (duration: 307.957495ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:36.812197Z","caller":"traceutil/trace.go:171","msg":"trace[1970697959] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4785; }","duration":"220.556275ms","start":"2026-06-13T22:51:36.591634Z","end":"2026-06-13T22:51:36.81219Z","steps":["trace[1970697959] 'agreement among raft nodes before linearized reading' (duration: 220.493934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:36.812275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:51:36.503964Z","time spent":"308.243923ms","remote":"127.0.0.1:40182","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-06-13T22:51:36.812285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.770931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b8c5c291d4fd25\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-06-13T22:51:36.812344Z","caller":"traceutil/trace.go:171","msg":"trace[1837734805] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b8c5c291d4fd25; range_end:; response_count:1; response_revision:4785; }","duration":"190.879034ms","start":"2026-06-13T22:51:36.621451Z","end":"2026-06-13T22:51:36.81233Z","steps":["trace[1837734805] 'agreement among raft nodes before linearized reading' (duration: 190.702199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:36.812706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.878548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-13T22:51:36.812716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.038064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-13T22:51:36.812735Z","caller":"traceutil/trace.go:171","msg":"trace[400233069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4785; }","duration":"159.944579ms","start":"2026-06-13T22:51:36.652783Z","end":"2026-06-13T22:51:36.812728Z","steps":["trace[400233069] 'agreement among raft nodes before linearized reading' (duration: 159.861417ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:36.812755Z","caller":"traceutil/trace.go:171","msg":"trace[732906404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4785; }","duration":"127.094275ms","start":"2026-06-13T22:51:36.68565Z","end":"2026-06-13T22:51:36.812744Z","steps":["trace[732906404] 'agreement among raft nodes before linearized reading' (duration: 126.954791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:36.812343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.909134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-13T22:51:36.812799Z","caller":"traceutil/trace.go:171","msg":"trace[420199906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4785; }","duration":"126.423477ms","start":"2026-06-13T22:51:36.68637Z","end":"2026-06-13T22:51:36.812793Z","steps":["trace[420199906] 'agreement among raft nodes before linearized reading' (duration: 125.880983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:36.812863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.573357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:36.812897Z","caller":"traceutil/trace.go:171","msg":"trace[1280040015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4785; }","duration":"130.630638ms","start":"2026-06-13T22:51:36.682257Z","end":"2026-06-13T22:51:36.812888Z","steps":["trace[1280040015] 'agreement among raft nodes before linearized reading' (duration: 130.582406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:36.813192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.061954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:51:36.813225Z","caller":"traceutil/trace.go:171","msg":"trace[1708584627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4785; }","duration":"146.120606ms","start":"2026-06-13T22:51:36.667097Z","end":"2026-06-13T22:51:36.813217Z","steps":["trace[1708584627] 'agreement among raft nodes before linearized reading' (duration: 145.337816ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:38.803998Z","caller":"traceutil/trace.go:171","msg":"trace[1651449294] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"104.5903ms","start":"2026-06-13T22:51:38.699383Z","end":"2026-06-13T22:51:38.803973Z","steps":["trace[1651449294] 'process raft request' (duration: 100.141103ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:38.804505Z","caller":"traceutil/trace.go:171","msg":"trace[1528839304] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4820; }","duration":"103.274626ms","start":"2026-06-13T22:51:38.70122Z","end":"2026-06-13T22:51:38.804494Z","steps":["trace[1528839304] 'process raft request' (duration: 103.227755ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:42.660754Z","caller":"traceutil/trace.go:171","msg":"trace[2100918681] linearizableReadLoop","detail":"{readStateIndex:5072; appliedIndex:5071; }","duration":"118.262763ms","start":"2026-06-13T22:51:42.542453Z","end":"2026-06-13T22:51:42.660716Z","steps":["trace[2100918681] 'read index received' (duration: 117.850612ms)","trace[2100918681] 'applied index is now lower than readState.Index' (duration: 410.371µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:42.660904Z","caller":"traceutil/trace.go:171","msg":"trace[2093124394] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"179.997836ms","start":"2026-06-13T22:51:42.480838Z","end":"2026-06-13T22:51:42.660836Z","steps":["trace[2093124394] 'process raft request' (duration: 179.520213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:42.661092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.604241ms","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-06-13T22:51:42.661179Z","caller":"traceutil/trace.go:171","msg":"trace[1627895515] 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:4885; }","duration":"118.730554ms","start":"2026-06-13T22:51:42.542424Z","end":"2026-06-13T22:51:42.661154Z","steps":["trace[1627895515] 'agreement among raft nodes before linearized reading' (duration: 118.472837ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.096521Z","caller":"traceutil/trace.go:171","msg":"trace[648796778] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"211.370039ms","start":"2026-06-13T22:51:46.885121Z","end":"2026-06-13T22:51:47.096492Z","steps":["trace[648796778] 'read index received' (duration: 211.092042ms)","trace[648796778] 'applied index is now lower than readState.Index' (duration: 275.977µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:47.09656Z","caller":"traceutil/trace.go:171","msg":"trace[1029939236] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"243.892487ms","start":"2026-06-13T22:51:46.85263Z","end":"2026-06-13T22:51:47.096523Z","steps":["trace[1029939236] 'process raft request' (duration: 243.593369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.096768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.606816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:47.096827Z","caller":"traceutil/trace.go:171","msg":"trace[153434728] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4913; }","duration":"211.697848ms","start":"2026-06-13T22:51:46.885112Z","end":"2026-06-13T22:51:47.09681Z","steps":["trace[153434728] 'agreement among raft nodes before linearized reading' (duration: 211.559874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.097066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.742509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-13T22:51:47.097117Z","caller":"traceutil/trace.go:171","msg":"trace[1735094585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4913; }","duration":"212.004206ms","start":"2026-06-13T22:51:46.885102Z","end":"2026-06-13T22:51:47.097106Z","steps":["trace[1735094585] 'agreement among raft nodes before linearized reading' (duration: 211.870922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.097191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.898783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:51:47.097279Z","caller":"traceutil/trace.go:171","msg":"trace[1765695982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4913; }","duration":"211.998746ms","start":"2026-06-13T22:51:46.885262Z","end":"2026-06-13T22:51:47.097261Z","steps":["trace[1765695982] 'agreement among raft nodes before linearized reading' (duration: 211.750639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.097408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.068657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-13T22:51:47.09754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.041021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-13T22:51:47.097587Z","caller":"traceutil/trace.go:171","msg":"trace[1943751249] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4913; }","duration":"211.214255ms","start":"2026-06-13T22:51:46.886359Z","end":"2026-06-13T22:51:47.097573Z","steps":["trace[1943751249] 'agreement among raft nodes before linearized reading' (duration: 211.109922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.097675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.465637ms","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-06-13T22:51:47.097708Z","caller":"traceutil/trace.go:171","msg":"trace[135468773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4913; }","duration":"212.512389ms","start":"2026-06-13T22:51:46.885187Z","end":"2026-06-13T22:51:47.097699Z","steps":["trace[135468773] 'agreement among raft nodes before linearized reading' (duration: 211.626426ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.097642Z","caller":"traceutil/trace.go:171","msg":"trace[1721531692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4913; }","duration":"212.275732ms","start":"2026-06-13T22:51:46.885247Z","end":"2026-06-13T22:51:47.097523Z","steps":["trace[1721531692] 'agreement among raft nodes before linearized reading' (duration: 211.700048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.097983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.648582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:51:47.09926Z","caller":"traceutil/trace.go:171","msg":"trace[547832783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4913; }","duration":"213.920335ms","start":"2026-06-13T22:51:46.885285Z","end":"2026-06-13T22:51:47.099206Z","steps":["trace[547832783] 'agreement among raft nodes before linearized reading' (duration: 212.617361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.340248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.745708ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:47.340369Z","caller":"traceutil/trace.go:171","msg":"trace[670577130] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4913; }","duration":"208.874461ms","start":"2026-06-13T22:51:47.131476Z","end":"2026-06-13T22:51:47.340351Z","steps":["trace[670577130] 'range keys from in-memory index tree' (duration: 208.734728ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.340597Z","caller":"traceutil/trace.go:171","msg":"trace[1170894611] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"237.062856ms","start":"2026-06-13T22:51:47.103502Z","end":"2026-06-13T22:51:47.340565Z","steps":["trace[1170894611] 'read index received' (duration: 226.980937ms)","trace[1170894611] 'applied index is now lower than readState.Index' (duration: 10.080509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:47.340969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.445986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-06-13T22:51:47.341016Z","caller":"traceutil/trace.go:171","msg":"trace[340421572] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4914; }","duration":"237.524377ms","start":"2026-06-13T22:51:47.103479Z","end":"2026-06-13T22:51:47.341003Z","steps":["trace[340421572] 'agreement among raft nodes before linearized reading' (duration: 237.347243ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.340774Z","caller":"traceutil/trace.go:171","msg":"trace[2016266249] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"237.748433ms","start":"2026-06-13T22:51:47.102959Z","end":"2026-06-13T22:51:47.340707Z","steps":["trace[2016266249] 'process raft request' (duration: 227.51169ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.351301Z","caller":"traceutil/trace.go:171","msg":"trace[1366898462] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"247.610246ms","start":"2026-06-13T22:51:47.103668Z","end":"2026-06-13T22:51:47.351278Z","steps":["trace[1366898462] 'process raft request' (duration: 247.38661ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.351294Z","caller":"traceutil/trace.go:171","msg":"trace[482547731] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"247.396021ms","start":"2026-06-13T22:51:47.103877Z","end":"2026-06-13T22:51:47.351273Z","steps":["trace[482547731] 'process raft request' (duration: 247.297019ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.35157Z","caller":"traceutil/trace.go:171","msg":"trace[2025519763] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"247.626447ms","start":"2026-06-13T22:51:47.10392Z","end":"2026-06-13T22:51:47.351546Z","steps":["trace[2025519763] 'process raft request' (duration: 247.312629ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.351742Z","caller":"traceutil/trace.go:171","msg":"trace[1399760522] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"247.241177ms","start":"2026-06-13T22:51:47.104492Z","end":"2026-06-13T22:51:47.351733Z","steps":["trace[1399760522] 'process raft request' (duration: 247.135954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.351896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.617136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:51:47.351947Z","caller":"traceutil/trace.go:171","msg":"trace[530986470] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4919; }","duration":"112.748219ms","start":"2026-06-13T22:51:47.239184Z","end":"2026-06-13T22:51:47.351933Z","steps":["trace[530986470] 'agreement among raft nodes before linearized reading' (duration: 112.669157ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.352133Z","caller":"traceutil/trace.go:171","msg":"trace[685321755] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"248.181111ms","start":"2026-06-13T22:51:47.10392Z","end":"2026-06-13T22:51:47.352101Z","steps":["trace[685321755] 'process raft request' (duration: 247.555125ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.528946Z","caller":"traceutil/trace.go:171","msg":"trace[1155468281] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"160.739762ms","start":"2026-06-13T22:51:47.368184Z","end":"2026-06-13T22:51:47.528923Z","steps":["trace[1155468281] 'read index received' (duration: 147.709367ms)","trace[1155468281] 'applied index is now lower than readState.Index' (duration: 13.029345ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:47.529203Z","caller":"traceutil/trace.go:171","msg":"trace[145575254] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"167.061485ms","start":"2026-06-13T22:51:47.362129Z","end":"2026-06-13T22:51:47.529191Z","steps":["trace[145575254] 'process raft request' (duration: 153.809564ms)","trace[145575254] 'compare' (duration: 12.557643ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:47.529558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.347567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-06-13T22:51:47.529594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.274895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b8c5c293d4905a\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-06-13T22:51:47.529629Z","caller":"traceutil/trace.go:171","msg":"trace[1407318708] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4921; }","duration":"161.44383ms","start":"2026-06-13T22:51:47.368168Z","end":"2026-06-13T22:51:47.529612Z","steps":["trace[1407318708] 'agreement among raft nodes before linearized reading' (duration: 161.232165ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.529689Z","caller":"traceutil/trace.go:171","msg":"trace[1073524932] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b8c5c293d4905a; range_end:; response_count:1; response_revision:4921; }","duration":"161.387799ms","start":"2026-06-13T22:51:47.368279Z","end":"2026-06-13T22:51:47.529667Z","steps":["trace[1073524932] 'agreement among raft nodes before linearized reading' (duration: 161.153543ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.733611Z","caller":"traceutil/trace.go:171","msg":"trace[1123653993] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"193.694889ms","start":"2026-06-13T22:51:47.539871Z","end":"2026-06-13T22:51:47.733566Z","steps":["trace[1123653993] 'process raft request' (duration: 193.484693ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.733686Z","caller":"traceutil/trace.go:171","msg":"trace[1296756118] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5110; }","duration":"125.376743ms","start":"2026-06-13T22:51:47.608286Z","end":"2026-06-13T22:51:47.733662Z","steps":["trace[1296756118] 'read index received' (duration: 125.121707ms)","trace[1296756118] 'applied index is now lower than readState.Index' (duration: 253.936µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:47.734036Z","caller":"traceutil/trace.go:171","msg":"trace[1721036255] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"192.169049ms","start":"2026-06-13T22:51:47.541834Z","end":"2026-06-13T22:51:47.734003Z","steps":["trace[1721036255] 'process raft request' (duration: 191.708327ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.734071Z","caller":"traceutil/trace.go:171","msg":"trace[139782889] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"193.886834ms","start":"2026-06-13T22:51:47.54016Z","end":"2026-06-13T22:51:47.734047Z","steps":["trace[139782889] 'process raft request' (duration: 193.305439ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.73411Z","caller":"traceutil/trace.go:171","msg":"trace[1397076140] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"134.628791ms","start":"2026-06-13T22:51:47.599468Z","end":"2026-06-13T22:51:47.734096Z","steps":["trace[1397076140] 'process raft request' (duration: 134.135278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.734649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-13T22:51:47.734729Z","caller":"traceutil/trace.go:171","msg":"trace[1255795938] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4926; }","duration":"154.729517ms","start":"2026-06-13T22:51:47.579978Z","end":"2026-06-13T22:51:47.734708Z","steps":["trace[1255795938] 'agreement among raft nodes before linearized reading' (duration: 154.468781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.735089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.78946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-06-13T22:51:47.73517Z","caller":"traceutil/trace.go:171","msg":"trace[420521479] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4926; }","duration":"191.918453ms","start":"2026-06-13T22:51:47.543216Z","end":"2026-06-13T22:51:47.735152Z","steps":["trace[420521479] 'agreement among raft nodes before linearized reading' (duration: 190.887136ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.801073Z","caller":"traceutil/trace.go:171","msg":"trace[886199502] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"179.089874ms","start":"2026-06-13T22:51:47.621956Z","end":"2026-06-13T22:51:47.801045Z","steps":["trace[886199502] 'process raft request' (duration: 178.653302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.801308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:47.801346Z","caller":"traceutil/trace.go:171","msg":"trace[2097160225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4927; }","duration":"120.156289ms","start":"2026-06-13T22:51:47.681181Z","end":"2026-06-13T22:51:47.801338Z","steps":["trace[2097160225] 'agreement among raft nodes before linearized reading' (duration: 120.070007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.961308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.417719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:47.961612Z","caller":"traceutil/trace.go:171","msg":"trace[201423020] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"214.127214ms","start":"2026-06-13T22:51:47.747445Z","end":"2026-06-13T22:51:47.961572Z","steps":["trace[201423020] 'process raft request' (duration: 87.336885ms)","trace[201423020] 'compare' (duration: 126.227255ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:47.961706Z","caller":"traceutil/trace.go:171","msg":"trace[1281272064] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"165.945616ms","start":"2026-06-13T22:51:47.795743Z","end":"2026-06-13T22:51:47.961689Z","steps":["trace[1281272064] 'process raft request' (duration: 165.751921ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.96174Z","caller":"traceutil/trace.go:171","msg":"trace[1701642268] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5115; }","duration":"160.945858ms","start":"2026-06-13T22:51:47.800769Z","end":"2026-06-13T22:51:47.961715Z","steps":["trace[1701642268] 'read index received' (duration: 33.932853ms)","trace[1701642268] 'applied index is now lower than readState.Index' (duration: 127.011465ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:47.962112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.800951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cd0cd195-686e-440f-85bc-7e294cf5b363\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-06-13T22:51:47.96211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.730538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b8c5c297d9ac09\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-06-13T22:51:47.962143Z","caller":"traceutil/trace.go:171","msg":"trace[1415661490] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"150.345855ms","start":"2026-06-13T22:51:47.811788Z","end":"2026-06-13T22:51:47.962134Z","steps":["trace[1415661490] 'process raft request' (duration: 150.192151ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.962154Z","caller":"traceutil/trace.go:171","msg":"trace[427653290] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cd0cd195-686e-440f-85bc-7e294cf5b363; range_end:; response_count:1; response_revision:4930; }","duration":"219.899813ms","start":"2026-06-13T22:51:47.742244Z","end":"2026-06-13T22:51:47.962143Z","steps":["trace[427653290] 'agreement among raft nodes before linearized reading' (duration: 219.594965ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.962177Z","caller":"traceutil/trace.go:171","msg":"trace[2031664847] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"149.500033ms","start":"2026-06-13T22:51:47.812663Z","end":"2026-06-13T22:51:47.962163Z","steps":["trace[2031664847] 'process raft request' (duration: 149.416821ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.962185Z","caller":"traceutil/trace.go:171","msg":"trace[2023976287] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b8c5c297d9ac09; range_end:; response_count:1; response_revision:4930; }","duration":"219.852091ms","start":"2026-06-13T22:51:47.742311Z","end":"2026-06-13T22:51:47.962163Z","steps":["trace[2023976287] 'agreement among raft nodes before linearized reading' (duration: 219.41942ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.962235Z","caller":"traceutil/trace.go:171","msg":"trace[1978365482] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"150.51813ms","start":"2026-06-13T22:51:47.8117Z","end":"2026-06-13T22:51:47.962218Z","steps":["trace[1978365482] 'process raft request' (duration: 149.871363ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.964231Z","caller":"traceutil/trace.go:171","msg":"trace[712651850] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"146.526227ms","start":"2026-06-13T22:51:47.815719Z","end":"2026-06-13T22:51:47.962245Z","steps":["trace[712651850] 'process raft request' (duration: 146.408603ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:47.978671Z","caller":"traceutil/trace.go:171","msg":"trace[20785400] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"111.732172ms","start":"2026-06-13T22:51:47.86691Z","end":"2026-06-13T22:51:47.978642Z","steps":["trace[20785400] 'process raft request' (duration: 111.603869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:47.978823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.769434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:47.979495Z","caller":"traceutil/trace.go:171","msg":"trace[1959699127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4934; }","duration":"177.496303ms","start":"2026-06-13T22:51:47.801956Z","end":"2026-06-13T22:51:47.979452Z","steps":["trace[1959699127] 'agreement among raft nodes before linearized reading' (duration: 176.748614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:48.244745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.334216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:48.244977Z","caller":"traceutil/trace.go:171","msg":"trace[1942478133] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4935; }","duration":"157.591733ms","start":"2026-06-13T22:51:48.087357Z","end":"2026-06-13T22:51:48.244948Z","steps":["trace[1942478133] 'count revisions from in-memory index tree' (duration: 157.237904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:48.244807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.507807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-13T22:51:48.245228Z","caller":"traceutil/trace.go:171","msg":"trace[1525408912] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4935; }","duration":"174.987149ms","start":"2026-06-13T22:51:48.070218Z","end":"2026-06-13T22:51:48.245205Z","steps":["trace[1525408912] 'range keys from in-memory index tree' (duration: 174.315972ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:48.85042Z","caller":"traceutil/trace.go:171","msg":"trace[783058152] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5123; }","duration":"194.343495ms","start":"2026-06-13T22:51:48.656051Z","end":"2026-06-13T22:51:48.850395Z","steps":["trace[783058152] 'read index received' (duration: 194.071878ms)","trace[783058152] 'applied index is now lower than readState.Index' (duration: 270.747µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:48.850651Z","caller":"traceutil/trace.go:171","msg":"trace[881117884] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"226.989512ms","start":"2026-06-13T22:51:48.623651Z","end":"2026-06-13T22:51:48.850641Z","steps":["trace[881117884] 'process raft request' (duration: 226.588902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:48.850972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.894659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-13T22:51:48.851014Z","caller":"traceutil/trace.go:171","msg":"trace[1680289814] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4936; }","duration":"194.954911ms","start":"2026-06-13T22:51:48.656046Z","end":"2026-06-13T22:51:48.851001Z","steps":["trace[1680289814] 'agreement among raft nodes before linearized reading' (duration: 194.804047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:48.851164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.530909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:51:48.851188Z","caller":"traceutil/trace.go:171","msg":"trace[838283286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4936; }","duration":"169.57608ms","start":"2026-06-13T22:51:48.681606Z","end":"2026-06-13T22:51:48.851182Z","steps":["trace[838283286] 'agreement among raft nodes before linearized reading' (duration: 169.537839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:48.851563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.305879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:51:48.851594Z","caller":"traceutil/trace.go:171","msg":"trace[166217369] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4936; }","duration":"163.361081ms","start":"2026-06-13T22:51:48.688226Z","end":"2026-06-13T22:51:48.851587Z","steps":["trace[166217369] 'agreement among raft nodes before linearized reading' (duration: 163.304169ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:00.207003Z","caller":"traceutil/trace.go:171","msg":"trace[1055832035] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"117.600036ms","start":"2026-06-13T22:52:00.089376Z","end":"2026-06-13T22:52:00.206976Z","steps":["trace[1055832035] 'process raft request' (duration: 117.427931ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:00.265326Z","caller":"traceutil/trace.go:171","msg":"trace[513382762] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"158.555272ms","start":"2026-06-13T22:52:00.106752Z","end":"2026-06-13T22:52:00.265308Z","steps":["trace[513382762] 'process raft request' (duration: 157.874375ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:35.77579Z","caller":"traceutil/trace.go:171","msg":"trace[381743285] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"112.709597ms","start":"2026-06-13T22:52:35.663047Z","end":"2026-06-13T22:52:35.775756Z","steps":["trace[381743285] 'process raft request' (duration: 112.506442ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:44.011819Z","caller":"traceutil/trace.go:171","msg":"trace[471799828] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"252.673816ms","start":"2026-06-13T22:52:43.75912Z","end":"2026-06-13T22:52:44.011794Z","steps":["trace[471799828] 'process raft request' (duration: 252.405669ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:44.011782Z","caller":"traceutil/trace.go:171","msg":"trace[2069938590] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5587; }","duration":"191.039259ms","start":"2026-06-13T22:52:43.820706Z","end":"2026-06-13T22:52:44.011746Z","steps":["trace[2069938590] 'read index received' (duration: 190.866975ms)","trace[2069938590] 'applied index is now lower than readState.Index' (duration: 170.704µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:44.012086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.714454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-13T22:52:44.012071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.336806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-13T22:52:44.012127Z","caller":"traceutil/trace.go:171","msg":"trace[112579763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5387; }","duration":"124.775036ms","start":"2026-06-13T22:52:43.887341Z","end":"2026-06-13T22:52:44.012116Z","steps":["trace[112579763] 'agreement among raft nodes before linearized reading' (duration: 124.674284ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:44.012152Z","caller":"traceutil/trace.go:171","msg":"trace[1672056916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5387; }","duration":"191.48662ms","start":"2026-06-13T22:52:43.820645Z","end":"2026-06-13T22:52:44.012132Z","steps":["trace[1672056916] 'agreement among raft nodes before linearized reading' (duration: 191.224384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:44.01207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.213431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-13T22:52:44.012268Z","caller":"traceutil/trace.go:171","msg":"trace[1762370832] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5387; }","duration":"181.443546ms","start":"2026-06-13T22:52:43.830803Z","end":"2026-06-13T22:52:44.012246Z","steps":["trace[1762370832] 'agreement among raft nodes before linearized reading' (duration: 181.139249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:44.012321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.949157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:52:44.012432Z","caller":"traceutil/trace.go:171","msg":"trace[275093060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5387; }","duration":"130.099641ms","start":"2026-06-13T22:52:43.882304Z","end":"2026-06-13T22:52:44.012404Z","steps":["trace[275093060] 'agreement among raft nodes before linearized reading' (duration: 129.805874ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:58.248448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2846} {"level":"info","ts":"2026-06-13T22:52:58.32731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2846,"took":"77.535008ms","hash":3422720774,"current-db-size-bytes":53833728,"current-db-size":"54 MB","current-db-size-in-use-bytes":28962816,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-13T22:52:58.32738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3422720774,"revision":2846,"compact-revision":-1} {"level":"warn","ts":"2026-06-13T22:55:29.524359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.278417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:55:29.524546Z","caller":"traceutil/trace.go:171","msg":"trace[653573045] transaction","detail":"{read_only:false; response_revision:6539; number_of_response:1; }","duration":"478.475272ms","start":"2026-06-13T22:55:29.046016Z","end":"2026-06-13T22:55:29.524491Z","steps":["trace[653573045] 'process raft request' (duration: 188.952109ms)","trace[653573045] 'compare' (duration: 289.124674ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:55:29.524589Z","caller":"traceutil/trace.go:171","msg":"trace[1174430802] transaction","detail":"{read_only:false; response_revision:6540; number_of_response:1; }","duration":"447.987519ms","start":"2026-06-13T22:55:29.07658Z","end":"2026-06-13T22:55:29.524567Z","steps":["trace[1174430802] 'process raft request' (duration: 447.916458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:29.524683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:29.076559Z","time spent":"448.087801ms","remote":"127.0.0.1:40182","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-06-13T22:55:29.524669Z","caller":"traceutil/trace.go:171","msg":"trace[82498818] linearizableReadLoop","detail":"{readStateIndex:6778; appliedIndex:6776; }","duration":"478.481812ms","start":"2026-06-13T22:55:29.046146Z","end":"2026-06-13T22:55:29.524628Z","steps":["trace[82498818] 'read index received' (duration: 10.209566ms)","trace[82498818] 'applied index is now lower than readState.Index' (duration: 468.267396ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:55:29.524882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:29.045999Z","time spent":"478.651335ms","remote":"127.0.0.1:40182","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-06-13T22:55:29.524994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.654652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"warn","ts":"2026-06-13T22:55:29.525033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.195169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-06-13T22:55:29.525052Z","caller":"traceutil/trace.go:171","msg":"trace[493209977] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6540; }","duration":"271.758525ms","start":"2026-06-13T22:55:29.253282Z","end":"2026-06-13T22:55:29.525041Z","steps":["trace[493209977] 'agreement among raft nodes before linearized reading' (duration: 271.647752ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:29.525082Z","caller":"traceutil/trace.go:171","msg":"trace[653694992] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6540; }","duration":"408.324261ms","start":"2026-06-13T22:55:29.116746Z","end":"2026-06-13T22:55:29.525071Z","steps":["trace[653694992] 'agreement among raft nodes before linearized reading' (duration: 408.083876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:29.525081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.663428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"warn","ts":"2026-06-13T22:55:29.525132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:29.116719Z","time spent":"408.404143ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4882,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-06-13T22:55:29.525156Z","caller":"traceutil/trace.go:171","msg":"trace[432897420] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6540; }","duration":"227.801001ms","start":"2026-06-13T22:55:29.297335Z","end":"2026-06-13T22:55:29.525136Z","steps":["trace[432897420] 'agreement among raft nodes before linearized reading' (duration: 227.535235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:29.5249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.752838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-13T22:55:29.525284Z","caller":"traceutil/trace.go:171","msg":"trace[1914346495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6540; }","duration":"479.144235ms","start":"2026-06-13T22:55:29.046121Z","end":"2026-06-13T22:55:29.525266Z","steps":["trace[1914346495] 'agreement among raft nodes before linearized reading' (duration: 478.681186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:29.52534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:29.046115Z","time spent":"479.206906ms","remote":"127.0.0.1:40182","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":"info","ts":"2026-06-13T22:55:33.159754Z","caller":"traceutil/trace.go:171","msg":"trace[1152943987] transaction","detail":"{read_only:false; response_revision:6557; number_of_response:1; }","duration":"100.64342ms","start":"2026-06-13T22:55:33.059085Z","end":"2026-06-13T22:55:33.159728Z","steps":["trace[1152943987] 'process raft request' (duration: 100.333014ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:41.715591Z","caller":"traceutil/trace.go:171","msg":"trace[966804006] transaction","detail":"{read_only:false; response_revision:6608; number_of_response:1; }","duration":"120.925309ms","start":"2026-06-13T22:55:41.594631Z","end":"2026-06-13T22:55:41.715556Z","steps":["trace[966804006] 'process raft request' (duration: 120.766415ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:58.503552Z","caller":"traceutil/trace.go:171","msg":"trace[59439898] transaction","detail":"{read_only:false; response_revision:6743; number_of_response:1; }","duration":"256.463035ms","start":"2026-06-13T22:55:58.247065Z","end":"2026-06-13T22:55:58.503528Z","steps":["trace[59439898] 'process raft request' (duration: 256.293232ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:59.357771Z","caller":"traceutil/trace.go:171","msg":"trace[1075705152] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"172.449175ms","start":"2026-06-13T22:55:59.18528Z","end":"2026-06-13T22:55:59.357729Z","steps":["trace[1075705152] 'process raft request' (duration: 172.18868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.358107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.435336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:55:59.358209Z","caller":"traceutil/trace.go:171","msg":"trace[89781327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6744; }","duration":"161.594129ms","start":"2026-06-13T22:55:59.196594Z","end":"2026-06-13T22:55:59.358188Z","steps":["trace[89781327] 'agreement among raft nodes before linearized reading' (duration: 161.289003ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:59.366247Z","caller":"traceutil/trace.go:171","msg":"trace[946283580] linearizableReadLoop","detail":"{readStateIndex:6993; appliedIndex:6992; }","duration":"161.008628ms","start":"2026-06-13T22:55:59.196635Z","end":"2026-06-13T22:55:59.357643Z","steps":["trace[946283580] 'read index received' (duration: 160.823814ms)","trace[946283580] 'applied index is now lower than readState.Index' (duration: 183.084µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:55:59.767905Z","caller":"traceutil/trace.go:171","msg":"trace[818215113] transaction","detail":"{read_only:false; response_revision:6745; number_of_response:1; }","duration":"398.146027ms","start":"2026-06-13T22:55:59.369732Z","end":"2026-06-13T22:55:59.767878Z","steps":["trace[818215113] 'process raft request' (duration: 329.642597ms)","trace[818215113] 'compare' (duration: 68.381948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:55:59.768421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.3697Z","time spent":"398.636736ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:55:59.91029Z","caller":"traceutil/trace.go:171","msg":"trace[1396574329] transaction","detail":"{read_only:false; response_revision:6746; number_of_response:1; }","duration":"539.525745ms","start":"2026-06-13T22:55:59.370735Z","end":"2026-06-13T22:55:59.91026Z","steps":["trace[1396574329] 'process raft request' (duration: 539.25696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.910459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.370713Z","time spent":"539.657527ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:55:59.910583Z","caller":"traceutil/trace.go:171","msg":"trace[137740953] transaction","detail":"{read_only:false; response_revision:6747; number_of_response:1; }","duration":"538.201369ms","start":"2026-06-13T22:55:59.372345Z","end":"2026-06-13T22:55:59.910547Z","steps":["trace[137740953] 'process raft request' (duration: 537.855692ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:59.910731Z","caller":"traceutil/trace.go:171","msg":"trace[720012412] transaction","detail":"{read_only:false; response_revision:6748; number_of_response:1; }","duration":"538.30328ms","start":"2026-06-13T22:55:59.37242Z","end":"2026-06-13T22:55:59.910723Z","steps":["trace[720012412] 'process raft request' (duration: 538.067546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.910775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.372416Z","time spent":"538.338912ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:55:59.910817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.17246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:55:59.910875Z","caller":"traceutil/trace.go:171","msg":"trace[1089218167] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:6749; }","duration":"451.300833ms","start":"2026-06-13T22:55:59.459561Z","end":"2026-06-13T22:55:59.910862Z","steps":["trace[1089218167] 'agreement among raft nodes before linearized reading' (duration: 451.185721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.910863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.372329Z","time spent":"538.360052ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:55:59.91092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:55:59.459539Z","time spent":"451.370264ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:55:59.911292Z","caller":"traceutil/trace.go:171","msg":"trace[2035967001] transaction","detail":"{read_only:false; response_revision:6749; number_of_response:1; }","duration":"245.376713ms","start":"2026-06-13T22:55:59.665897Z","end":"2026-06-13T22:55:59.911274Z","steps":["trace[2035967001] 'process raft request' (duration: 244.71196ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:59.910687Z","caller":"traceutil/trace.go:171","msg":"trace[2049843299] linearizableReadLoop","detail":"{readStateIndex:6997; appliedIndex:6993; }","duration":"451.043908ms","start":"2026-06-13T22:55:59.459618Z","end":"2026-06-13T22:55:59.910662Z","steps":["trace[2049843299] 'read index received' (duration: 239.67194ms)","trace[2049843299] 'applied index is now lower than readState.Index' (duration: 211.370518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:55:59.91204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.034178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:55:59.912102Z","caller":"traceutil/trace.go:171","msg":"trace[1166528029] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:6749; }","duration":"237.114829ms","start":"2026-06-13T22:55:59.67497Z","end":"2026-06-13T22:55:59.912085Z","steps":["trace[1166528029] 'agreement among raft nodes before linearized reading' (duration: 236.987947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.912374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.69258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-13T22:55:59.912395Z","caller":"traceutil/trace.go:171","msg":"trace[802671728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6749; }","duration":"100.762221ms","start":"2026-06-13T22:55:59.811627Z","end":"2026-06-13T22:55:59.91239Z","steps":["trace[802671728] 'agreement among raft nodes before linearized reading' (duration: 100.665599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:59.912622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.444756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:55:59.912659Z","caller":"traceutil/trace.go:171","msg":"trace[1426709277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6749; }","duration":"166.525158ms","start":"2026-06-13T22:55:59.746127Z","end":"2026-06-13T22:55:59.912652Z","steps":["trace[1426709277] 'agreement among raft nodes before linearized reading' (duration: 166.409495ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:57:58.257987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5497} {"level":"info","ts":"2026-06-13T22:57:58.346664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5497,"took":"86.598847ms","hash":800246739,"current-db-size-bytes":53833728,"current-db-size":"54 MB","current-db-size-in-use-bytes":18231296,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-13T22:57:58.346732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":800246739,"revision":5497,"compact-revision":2846}