{"level":"warn","ts":"2026-05-05T09:41:31.962689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:41:31.962829Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T09:41:31.962905Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:41:31.962912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-05-05T09:41:31.963204Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T09:41:31.964153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-05-05T09:41:31.96425Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T09:41:31.968915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.412399ms"} {"level":"info","ts":"2026-05-05T09:41:31.975483Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-05-05T09:41:31.9756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:41:31.975646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-05-05T09:41:31.975661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:41:31.975674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-05-05T09:41:31.975739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-05-05T09:41:31.982531Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:41:31.984199Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:41:31.985355Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T09:41:31.98789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:41:31.988076Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:41:31.988298Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:41:31.98838Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:41:31.988401Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T09:41:31.990322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-05-05T09:41:31.990886Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-05-05T09:41:31.993471Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T09:41:31.993631Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-05-05T09:41:31.993671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-05-05T09:41:31.993871Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:41:31.993777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:41:32.476912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:41:32.477011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:41:32.477105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-05-05T09:41:32.477134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:41:32.477148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-05-05T09:41:32.477167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-05-05T09:41:32.47718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-05-05T09:41:32.478329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:41:32.478403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:41:32.478468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:32.479281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:41:32.479589Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:32.479664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:32.479707Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:41:32.48093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:41:32.480968Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:41:32.481451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:41:32.48231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-05-05T09:41:36.379813Z","caller":"traceutil/trace.go:171","msg":"trace[1321102361] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"265.369356ms","start":"2026-05-05T09:41:36.114418Z","end":"2026-05-05T09:41:36.379788Z","steps":["trace[1321102361] 'process raft request' (duration: 265.228223ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:41:36.606037Z","caller":"traceutil/trace.go:171","msg":"trace[231172994] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"196.075856ms","start":"2026-05-05T09:41:36.409931Z","end":"2026-05-05T09:41:36.606007Z","steps":["trace[231172994] 'process raft request' (duration: 192.498783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:01.950549Z","caller":"traceutil/trace.go:171","msg":"trace[1078126312] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"123.175431ms","start":"2026-05-05T09:42:01.827348Z","end":"2026-05-05T09:42:01.950524Z","steps":["trace[1078126312] 'process raft request' (duration: 122.730105ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:02.237596Z","caller":"traceutil/trace.go:171","msg":"trace[1916047908] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"371.781012ms","start":"2026-05-05T09:42:01.865742Z","end":"2026-05-05T09:42:02.237523Z","steps":["trace[1916047908] 'process raft request' (duration: 369.776354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:02.238018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:01.865713Z","time spent":"371.983944ms","remote":"127.0.0.1:36840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:42:02.237626Z","caller":"traceutil/trace.go:171","msg":"trace[959178043] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"338.496254ms","start":"2026-05-05T09:42:01.899108Z","end":"2026-05-05T09:42:02.237604Z","steps":["trace[959178043] 'process raft request' (duration: 338.354112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:02.23836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:01.899087Z","time spent":"339.036582ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:42:02.24435Z","caller":"traceutil/trace.go:171","msg":"trace[1389888594] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"244.199229ms","start":"2026-05-05T09:42:02.00012Z","end":"2026-05-05T09:42:02.244319Z","steps":["trace[1389888594] 'process raft request' (duration: 242.157802ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:02.251136Z","caller":"traceutil/trace.go:171","msg":"trace[240064114] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"243.956926ms","start":"2026-05-05T09:42:02.007126Z","end":"2026-05-05T09:42:02.251083Z","steps":["trace[240064114] 'read index received' (duration: 228.390244ms)","trace[240064114] 'applied index is now lower than readState.Index' (duration: 15.565742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:42:02.254632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.492463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T09:42:02.254712Z","caller":"traceutil/trace.go:171","msg":"trace[1788707817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"247.643336ms","start":"2026-05-05T09:42:02.007055Z","end":"2026-05-05T09:42:02.254699Z","steps":["trace[1788707817] 'agreement among raft nodes before linearized reading' (duration: 244.159088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:02.254801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.121381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T09:42:02.254876Z","caller":"traceutil/trace.go:171","msg":"trace[780670043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:581; }","duration":"242.199262ms","start":"2026-05-05T09:42:02.012655Z","end":"2026-05-05T09:42:02.254854Z","steps":["trace[780670043] 'agreement among raft nodes before linearized reading' (duration: 242.07582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:03.523188Z","caller":"traceutil/trace.go:171","msg":"trace[1896171] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"359.563691ms","start":"2026-05-05T09:42:03.163604Z","end":"2026-05-05T09:42:03.523168Z","steps":["trace[1896171] 'process raft request' (duration: 359.4316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:03.523455Z","caller":"traceutil/trace.go:171","msg":"trace[277009281] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:631; }","duration":"303.237408ms","start":"2026-05-05T09:42:03.220197Z","end":"2026-05-05T09:42:03.523434Z","steps":["trace[277009281] 'read index received' (duration: 303.232098ms)","trace[277009281] 'applied index is now lower than readState.Index' (duration: 4.49µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:42:03.523488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.163588Z","time spent":"359.670992ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:42:03.52356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.346739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:03.523588Z","caller":"traceutil/trace.go:171","msg":"trace[280868513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"303.41234ms","start":"2026-05-05T09:42:03.220167Z","end":"2026-05-05T09:42:03.523579Z","steps":["trace[280868513] 'agreement among raft nodes before linearized reading' (duration: 303.352649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:03.52361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.220153Z","time spent":"303.45146ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:42:04.008974Z","caller":"traceutil/trace.go:171","msg":"trace[1214127733] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"485.461583ms","start":"2026-05-05T09:42:03.523498Z","end":"2026-05-05T09:42:04.008959Z","steps":["trace[1214127733] 'read index received' (duration: 484.46318ms)","trace[1214127733] 'applied index is now lower than readState.Index' (duration: 997.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:42:04.008985Z","caller":"traceutil/trace.go:171","msg":"trace[1360187030] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"707.307622ms","start":"2026-05-05T09:42:03.301662Z","end":"2026-05-05T09:42:04.00897Z","steps":["trace[1360187030] 'process raft request' (duration: 706.324079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.009085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.301647Z","time spent":"707.378423ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:42:04.009135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.384808ms","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-05-05T09:42:04.009153Z","caller":"traceutil/trace.go:171","msg":"trace[2045836327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"575.432578ms","start":"2026-05-05T09:42:03.433715Z","end":"2026-05-05T09:42:04.009147Z","steps":["trace[2045836327] 'agreement among raft nodes before linearized reading' (duration: 575.317876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.009167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.433698Z","time spent":"575.465449ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:42:04.016061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.166623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:04.016175Z","caller":"traceutil/trace.go:171","msg":"trace[2029481851] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"492.320255ms","start":"2026-05-05T09:42:03.523828Z","end":"2026-05-05T09:42:04.016148Z","steps":["trace[2029481851] 'agreement among raft nodes before linearized reading' (duration: 492.142772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.016166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.538155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:42:04.016225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.52381Z","time spent":"492.402815ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-05T09:42:04.016283Z","caller":"traceutil/trace.go:171","msg":"trace[1016962977] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"320.653038ms","start":"2026-05-05T09:42:03.69558Z","end":"2026-05-05T09:42:04.016233Z","steps":["trace[1016962977] 'agreement among raft nodes before linearized reading' (duration: 320.518326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.016327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.695569Z","time spent":"320.748529ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-05T09:42:04.016463Z","caller":"traceutil/trace.go:171","msg":"trace[1109247547] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"119.462526ms","start":"2026-05-05T09:42:03.896974Z","end":"2026-05-05T09:42:04.016437Z","steps":["trace[1109247547] 'process raft request' (duration: 118.840848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.016494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.701066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:42:04.016536Z","caller":"traceutil/trace.go:171","msg":"trace[1769849365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"491.761986ms","start":"2026-05-05T09:42:03.524761Z","end":"2026-05-05T09:42:04.016523Z","steps":["trace[1769849365] 'agreement among raft nodes before linearized reading' (duration: 491.696686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.01656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.524749Z","time spent":"491.804677ms","remote":"127.0.0.1:36738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:42:04.016606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.743255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T09:42:04.016652Z","caller":"traceutil/trace.go:171","msg":"trace[2084215647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"381.821126ms","start":"2026-05-05T09:42:03.634813Z","end":"2026-05-05T09:42:04.016634Z","steps":["trace[2084215647] 'agreement among raft nodes before linearized reading' (duration: 381.635683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:04.016695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:03.634782Z","time spent":"381.898997ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:42:04.996288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216334861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:42:05.080204Z","caller":"traceutil/trace.go:171","msg":"trace[2114715228] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"584.25608ms","start":"2026-05-05T09:42:04.49592Z","end":"2026-05-05T09:42:05.080176Z","steps":["trace[2114715228] 'read index received' (duration: 582.831691ms)","trace[2114715228] 'applied index is now lower than readState.Index' (duration: 1.423209ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:42:05.080257Z","caller":"traceutil/trace.go:171","msg":"trace[1633297328] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"599.017256ms","start":"2026-05-05T09:42:04.481212Z","end":"2026-05-05T09:42:05.080229Z","steps":["trace[1633297328] 'process raft request' (duration: 597.933221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:05.080383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:04.481185Z","time spent":"599.136877ms","remote":"127.0.0.1:36862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T09:42:05.080547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.530623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-05T09:42:05.080802Z","caller":"traceutil/trace.go:171","msg":"trace[2016300126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:626; }","duration":"584.737817ms","start":"2026-05-05T09:42:04.495889Z","end":"2026-05-05T09:42:05.080626Z","steps":["trace[2016300126] 'agreement among raft nodes before linearized reading' (duration: 584.415473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:05.080853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:04.495874Z","time spent":"584.965329ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-05T09:42:05.081575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.010809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-05T09:42:05.081814Z","caller":"traceutil/trace.go:171","msg":"trace[1540799331] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"386.084709ms","start":"2026-05-05T09:42:04.695525Z","end":"2026-05-05T09:42:05.081609Z","steps":["trace[1540799331] 'agreement among raft nodes before linearized reading' (duration: 385.955208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:05.081846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:04.695511Z","time spent":"386.327533ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-05T09:42:05.082015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.28133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-05T09:42:05.082044Z","caller":"traceutil/trace.go:171","msg":"trace[911204997] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"186.332851ms","start":"2026-05-05T09:42:04.895703Z","end":"2026-05-05T09:42:05.082036Z","steps":["trace[911204997] 'agreement among raft nodes before linearized reading' (duration: 186.252359ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:13.710172Z","caller":"traceutil/trace.go:171","msg":"trace[399117716] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"136.925558ms","start":"2026-05-05T09:42:13.573222Z","end":"2026-05-05T09:42:13.710147Z","steps":["trace[399117716] 'process raft request' (duration: 136.783975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:30.135356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.312223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:42:30.135485Z","caller":"traceutil/trace.go:171","msg":"trace[521663892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"340.510165ms","start":"2026-05-05T09:42:29.794944Z","end":"2026-05-05T09:42:30.135454Z","steps":["trace[521663892] 'range keys from in-memory index tree' (duration: 340.066681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:30.135542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:29.794932Z","time spent":"340.597646ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T09:42:30.135599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.816997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-brzbv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-05T09:42:30.135734Z","caller":"traceutil/trace.go:171","msg":"trace[841989458] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-brzbv; range_end:; response_count:1; response_revision:797; }","duration":"362.051779ms","start":"2026-05-05T09:42:29.773652Z","end":"2026-05-05T09:42:30.135704Z","steps":["trace[841989458] 'range keys from in-memory index tree' (duration: 361.475634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:30.135804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:42:29.773623Z","time spent":"362.16987ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-brzbv\" "} {"level":"info","ts":"2026-05-05T09:42:30.306084Z","caller":"traceutil/trace.go:171","msg":"trace[328503554] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"163.562846ms","start":"2026-05-05T09:42:30.142487Z","end":"2026-05-05T09:42:30.30605Z","steps":["trace[328503554] 'process raft request' (duration: 163.353594ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:30.362717Z","caller":"traceutil/trace.go:171","msg":"trace[113039687] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"214.16051ms","start":"2026-05-05T09:42:30.148526Z","end":"2026-05-05T09:42:30.362686Z","steps":["trace[113039687] 'read index received' (duration: 157.376052ms)","trace[113039687] 'applied index is now lower than readState.Index' (duration: 56.783548ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:42:30.362913Z","caller":"traceutil/trace.go:171","msg":"trace[314059732] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"219.894379ms","start":"2026-05-05T09:42:30.142959Z","end":"2026-05-05T09:42:30.362853Z","steps":["trace[314059732] 'process raft request' (duration: 219.573126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:30.363017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.450243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T09:42:30.363042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.875467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T09:42:30.363101Z","caller":"traceutil/trace.go:171","msg":"trace[1217837843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"213.945057ms","start":"2026-05-05T09:42:30.149137Z","end":"2026-05-05T09:42:30.363082Z","steps":["trace[1217837843] 'agreement among raft nodes before linearized reading' (duration: 213.772596ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:30.363057Z","caller":"traceutil/trace.go:171","msg":"trace[951214622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"214.541114ms","start":"2026-05-05T09:42:30.148503Z","end":"2026-05-05T09:42:30.363044Z","steps":["trace[951214622] 'agreement among raft nodes before linearized reading' (duration: 214.329472ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:03.40916Z","caller":"traceutil/trace.go:171","msg":"trace[1010060526] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"187.938469ms","start":"2026-05-05T09:43:03.221197Z","end":"2026-05-05T09:43:03.409136Z","steps":["trace[1010060526] 'read index received' (duration: 187.788432ms)","trace[1010060526] 'applied index is now lower than readState.Index' (duration: 148.977µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:03.409357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.100246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:03.409432Z","caller":"traceutil/trace.go:171","msg":"trace[1671217913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"188.258012ms","start":"2026-05-05T09:43:03.221124Z","end":"2026-05-05T09:43:03.409382Z","steps":["trace[1671217913] 'agreement among raft nodes before linearized reading' (duration: 188.108526ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:03.409432Z","caller":"traceutil/trace.go:171","msg":"trace[351331780] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"329.493927ms","start":"2026-05-05T09:43:03.079823Z","end":"2026-05-05T09:43:03.409316Z","steps":["trace[351331780] 'process raft request' (duration: 329.150693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:03.410056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:03.07981Z","time spent":"329.815041ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:10.817754Z","caller":"traceutil/trace.go:171","msg":"trace[1968090273] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"263.866217ms","start":"2026-05-05T09:43:10.55386Z","end":"2026-05-05T09:43:10.817726Z","steps":["trace[1968090273] 'read index received' (duration: 260.41564ms)","trace[1968090273] 'applied index is now lower than readState.Index' (duration: 3.449147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:10.818139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.606668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T09:43:10.818279Z","caller":"traceutil/trace.go:171","msg":"trace[1436304977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"261.734702ms","start":"2026-05-05T09:43:10.556495Z","end":"2026-05-05T09:43:10.818229Z","steps":["trace[1436304977] 'agreement among raft nodes before linearized reading' (duration: 261.493813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:10.818292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.81114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:2122"} {"level":"info","ts":"2026-05-05T09:43:10.818403Z","caller":"traceutil/trace.go:171","msg":"trace[775943157] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1283; }","duration":"116.964496ms","start":"2026-05-05T09:43:10.70141Z","end":"2026-05-05T09:43:10.818374Z","steps":["trace[775943157] 'agreement among raft nodes before linearized reading' (duration: 116.625153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:10.818315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.25301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T09:43:10.81853Z","caller":"traceutil/trace.go:171","msg":"trace[760284283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"201.52023ms","start":"2026-05-05T09:43:10.616986Z","end":"2026-05-05T09:43:10.818506Z","steps":["trace[760284283] 'agreement among raft nodes before linearized reading' (duration: 201.093173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:10.818945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.081107ms","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-05-05T09:43:10.819018Z","caller":"traceutil/trace.go:171","msg":"trace[728060533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"265.17228ms","start":"2026-05-05T09:43:10.553828Z","end":"2026-05-05T09:43:10.819Z","steps":["trace[728060533] 'agreement among raft nodes before linearized reading' (duration: 264.000172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:19.373693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216336337,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:43:19.457121Z","caller":"traceutil/trace.go:171","msg":"trace[1075274077] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"584.323963ms","start":"2026-05-05T09:43:18.872776Z","end":"2026-05-05T09:43:19.4571Z","steps":["trace[1075274077] 'read index received' (duration: 584.148676ms)","trace[1075274077] 'applied index is now lower than readState.Index' (duration: 174.726µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:19.457439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.635254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T09:43:19.457547Z","caller":"traceutil/trace.go:171","msg":"trace[1242652757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"584.79389ms","start":"2026-05-05T09:43:18.872729Z","end":"2026-05-05T09:43:19.457523Z","steps":["trace[1242652757] 'agreement among raft nodes before linearized reading' (duration: 584.495549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:19.457615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:18.872719Z","time spent":"584.884744ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:43:19.457709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.070366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T09:43:19.457704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.036611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:19.457808Z","caller":"traceutil/trace.go:171","msg":"trace[405460729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"237.195906ms","start":"2026-05-05T09:43:19.220587Z","end":"2026-05-05T09:43:19.457783Z","steps":["trace[405460729] 'agreement among raft nodes before linearized reading' (duration: 236.969298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:19.45777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.593014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:19.457751Z","caller":"traceutil/trace.go:171","msg":"trace[1524125105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"580.148279ms","start":"2026-05-05T09:43:18.877589Z","end":"2026-05-05T09:43:19.457738Z","steps":["trace[1524125105] 'agreement among raft nodes before linearized reading' (duration: 579.733045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:19.457727Z","caller":"traceutil/trace.go:171","msg":"trace[1372654475] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"587.197258ms","start":"2026-05-05T09:43:18.870487Z","end":"2026-05-05T09:43:19.457684Z","steps":["trace[1372654475] 'process raft request' (duration: 586.492362ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:19.457874Z","caller":"traceutil/trace.go:171","msg":"trace[1632011567] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1338; }","duration":"376.76307ms","start":"2026-05-05T09:43:19.081087Z","end":"2026-05-05T09:43:19.45785Z","steps":["trace[1632011567] 'agreement among raft nodes before linearized reading' (duration: 376.534151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:19.457886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:18.877582Z","time spent":"580.291554ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:43:19.457889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.218022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T09:43:19.457923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:19.081067Z","time spent":"376.848033ms","remote":"127.0.0.1:37166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-05T09:43:19.457947Z","caller":"traceutil/trace.go:171","msg":"trace[1682343592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"580.289874ms","start":"2026-05-05T09:43:18.877641Z","end":"2026-05-05T09:43:19.457931Z","steps":["trace[1682343592] 'agreement among raft nodes before linearized reading' (duration: 580.101668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:19.457981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:18.877634Z","time spent":"580.340046ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:43:19.458119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:18.870452Z","time spent":"587.462857ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:43:20.161415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216336341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:43:20.316295Z","caller":"traceutil/trace.go:171","msg":"trace[1518571881] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"851.640086ms","start":"2026-05-05T09:43:19.46463Z","end":"2026-05-05T09:43:20.31627Z","steps":["trace[1518571881] 'process raft request' (duration: 851.585594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:20.316483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:19.46462Z","time spent":"851.78589ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:20.316872Z","caller":"traceutil/trace.go:171","msg":"trace[1517978306] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"853.182242ms","start":"2026-05-05T09:43:19.463654Z","end":"2026-05-05T09:43:20.316836Z","steps":["trace[1517978306] 'process raft request' (duration: 850.91959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:20.318299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:19.463647Z","time spent":"853.647178ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:43:20.368581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.539187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:20.368686Z","caller":"traceutil/trace.go:171","msg":"trace[1867609096] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1343; }","duration":"707.676802ms","start":"2026-05-05T09:43:19.660989Z","end":"2026-05-05T09:43:20.368666Z","steps":["trace[1867609096] 'agreement among raft nodes before linearized reading' (duration: 707.388702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:20.368741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:19.660959Z","time spent":"707.764285ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T09:43:20.368555Z","caller":"traceutil/trace.go:171","msg":"trace[526372981] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"550.134236ms","start":"2026-05-05T09:43:19.818084Z","end":"2026-05-05T09:43:20.368218Z","steps":["trace[526372981] 'process raft request' (duration: 549.858466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:20.369458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:19.818062Z","time spent":"551.284708ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:20.36818Z","caller":"traceutil/trace.go:171","msg":"trace[1224798438] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1382; }","duration":"707.157434ms","start":"2026-05-05T09:43:19.660997Z","end":"2026-05-05T09:43:20.368154Z","steps":["trace[1224798438] 'read index received' (duration: 653.650812ms)","trace[1224798438] 'applied index is now lower than readState.Index' (duration: 53.505692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:21.973348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216336362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:43:22.473714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216336362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T09:43:22.755875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.287106763s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T09:43:22.756336Z","caller":"traceutil/trace.go:171","msg":"trace[1038465354] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.287648253s","start":"2026-05-05T09:43:21.46866Z","end":"2026-05-05T09:43:22.756308Z","steps":["trace[1038465354] 'process raft request' (duration: 1.287428835s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.756624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:21.468636Z","time spent":"1.287788918s","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:43:22.76129Z","caller":"traceutil/trace.go:171","msg":"trace[740035046] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1393; }","duration":"1.288406428s","start":"2026-05-05T09:43:21.472857Z","end":"2026-05-05T09:43:22.761263Z","steps":["trace[740035046] 'read index received' (duration: 1.283353699s)","trace[740035046] 'applied index is now lower than readState.Index' (duration: 5.051569ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:22.761396Z","caller":"traceutil/trace.go:171","msg":"trace[687725878] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"387.146734ms","start":"2026-05-05T09:43:22.374225Z","end":"2026-05-05T09:43:22.761371Z","steps":["trace[687725878] 'process raft request' (duration: 386.924346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.761548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288688338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T09:43:22.761552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.374201Z","time spent":"387.255008ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:43:22.761578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.984271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T09:43:22.76161Z","caller":"traceutil/trace.go:171","msg":"trace[1299998551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"1.288824102s","start":"2026-05-05T09:43:21.47277Z","end":"2026-05-05T09:43:22.761594Z","steps":["trace[1299998551] 'agreement among raft nodes before linearized reading' (duration: 1.288610995s)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:22.761703Z","caller":"traceutil/trace.go:171","msg":"trace[1025406847] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1351; }","duration":"644.159617ms","start":"2026-05-05T09:43:22.117524Z","end":"2026-05-05T09:43:22.761684Z","steps":["trace[1025406847] 'agreement among raft nodes before linearized reading' (duration: 643.891388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.761735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:21.472735Z","time spent":"1.288985108s","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:43:22.76175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.117494Z","time spent":"644.24412ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":579,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-05T09:43:22.761811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.389512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T09:43:22.761883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.867279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:43:22.761902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.492036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T09:43:22.761914Z","caller":"traceutil/trace.go:171","msg":"trace[1593532304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"439.542108ms","start":"2026-05-05T09:43:22.322351Z","end":"2026-05-05T09:43:22.761893Z","steps":["trace[1593532304] 'agreement among raft nodes before linearized reading' (duration: 439.213386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:22.761925Z","caller":"traceutil/trace.go:171","msg":"trace[64773182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"541.90787ms","start":"2026-05-05T09:43:22.220005Z","end":"2026-05-05T09:43:22.761913Z","steps":["trace[64773182] 'agreement among raft nodes before linearized reading' (duration: 541.837278ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:22.761932Z","caller":"traceutil/trace.go:171","msg":"trace[1309728696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"439.564989ms","start":"2026-05-05T09:43:22.322357Z","end":"2026-05-05T09:43:22.761922Z","steps":["trace[1309728696] 'agreement among raft nodes before linearized reading' (duration: 439.472825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.761963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.322347Z","time spent":"439.61069ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:43:22.761962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.219935Z","time spent":"542.019425ms","remote":"127.0.0.1:36738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:43:22.761968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.322325Z","time spent":"439.627711ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:43:22.761999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.025133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T09:43:22.762049Z","caller":"traceutil/trace.go:171","msg":"trace[1112484928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"218.113096ms","start":"2026-05-05T09:43:22.543922Z","end":"2026-05-05T09:43:22.762035Z","steps":["trace[1112484928] 'agreement among raft nodes before linearized reading' (duration: 217.95327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.762164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.775558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-8t4zt\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-05T09:43:22.762241Z","caller":"traceutil/trace.go:171","msg":"trace[1496791123] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-8t4zt; range_end:; response_count:1; response_revision:1351; }","duration":"426.903963ms","start":"2026-05-05T09:43:22.33532Z","end":"2026-05-05T09:43:22.762224Z","steps":["trace[1496791123] 'agreement among raft nodes before linearized reading' (duration: 426.750218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:22.762297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:22.3353Z","time spent":"426.984156ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4218,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-8t4zt\" "} {"level":"info","ts":"2026-05-05T09:43:53.039028Z","caller":"traceutil/trace.go:171","msg":"trace[969438487] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"233.254033ms","start":"2026-05-05T09:43:52.805746Z","end":"2026-05-05T09:43:53.039Z","steps":["trace[969438487] 'read index received' (duration: 233.105539ms)","trace[969438487] 'applied index is now lower than readState.Index' (duration: 147.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:53.039077Z","caller":"traceutil/trace.go:171","msg":"trace[196837116] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"245.408546ms","start":"2026-05-05T09:43:52.793649Z","end":"2026-05-05T09:43:53.039057Z","steps":["trace[196837116] 'process raft request' (duration: 245.210011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:53.039299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.519459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T09:43:53.039348Z","caller":"traceutil/trace.go:171","msg":"trace[1549577671] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"233.645603ms","start":"2026-05-05T09:43:52.805687Z","end":"2026-05-05T09:43:53.039333Z","steps":["trace[1549577671] 'agreement among raft nodes before linearized reading' (duration: 233.446618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:53.188237Z","caller":"traceutil/trace.go:171","msg":"trace[87087439] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"149.104733ms","start":"2026-05-05T09:43:53.039112Z","end":"2026-05-05T09:43:53.188216Z","steps":["trace[87087439] 'read index received' (duration: 140.645859ms)","trace[87087439] 'applied index is now lower than readState.Index' (duration: 8.457804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:43:53.188376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.039737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:43:53.188419Z","caller":"traceutil/trace.go:171","msg":"trace[1530887832] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1871; }","duration":"226.13738ms","start":"2026-05-05T09:43:52.962269Z","end":"2026-05-05T09:43:53.188407Z","steps":["trace[1530887832] 'agreement among raft nodes before linearized reading' (duration: 226.053387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:53.188268Z","caller":"traceutil/trace.go:171","msg":"trace[1366430491] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"150.317605ms","start":"2026-05-05T09:43:53.037924Z","end":"2026-05-05T09:43:53.188242Z","steps":["trace[1366430491] 'process raft request' (duration: 141.886133ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:53.623798Z","caller":"traceutil/trace.go:171","msg":"trace[494189288] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"312.558604ms","start":"2026-05-05T09:43:53.311217Z","end":"2026-05-05T09:43:53.623776Z","steps":["trace[494189288] 'process raft request' (duration: 312.425851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:53.623957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:43:53.311195Z","time spent":"312.686957ms","remote":"127.0.0.1:47826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2507,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:43:53.626287Z","caller":"traceutil/trace.go:171","msg":"trace[1677021547] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"125.96763ms","start":"2026-05-05T09:43:53.500293Z","end":"2026-05-05T09:43:53.626261Z","steps":["trace[1677021547] 'read index received' (duration: 123.33325ms)","trace[1677021547] 'applied index is now lower than readState.Index' (duration: 2.6334ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:53.626405Z","caller":"traceutil/trace.go:171","msg":"trace[2083976830] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"261.779131ms","start":"2026-05-05T09:43:53.364595Z","end":"2026-05-05T09:43:53.626374Z","steps":["trace[2083976830] 'process raft request' (duration: 261.499904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:53.626552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.239978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-676cc3d2-4bfa-4645-9096-63bc6899acce\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-05T09:43:53.626599Z","caller":"traceutil/trace.go:171","msg":"trace[1301899279] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-676cc3d2-4bfa-4645-9096-63bc6899acce; range_end:; response_count:1; response_revision:1878; }","duration":"126.344851ms","start":"2026-05-05T09:43:53.500242Z","end":"2026-05-05T09:43:53.626587Z","steps":["trace[1301899279] 'agreement among raft nodes before linearized reading' (duration: 126.161926ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:23.299897Z","caller":"traceutil/trace.go:171","msg":"trace[147402213] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"167.762578ms","start":"2026-05-05T09:44:23.132093Z","end":"2026-05-05T09:44:23.299855Z","steps":["trace[147402213] 'process raft request' (duration: 167.536743ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:23.463554Z","caller":"traceutil/trace.go:171","msg":"trace[359895917] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2176; }","duration":"123.937918ms","start":"2026-05-05T09:44:23.339406Z","end":"2026-05-05T09:44:23.463343Z","steps":["trace[359895917] 'read index received' (duration: 48.933307ms)","trace[359895917] 'applied index is now lower than readState.Index' (duration: 75.003461ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:23.463676Z","caller":"traceutil/trace.go:171","msg":"trace[633260516] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"159.392021ms","start":"2026-05-05T09:44:23.304272Z","end":"2026-05-05T09:44:23.463664Z","steps":["trace[633260516] 'process raft request' (duration: 84.112364ms)","trace[633260516] 'compare' (duration: 74.714665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:23.463985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.571062ms","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-05-05T09:44:23.46402Z","caller":"traceutil/trace.go:171","msg":"trace[1294642386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2097; }","duration":"124.651023ms","start":"2026-05-05T09:44:23.339359Z","end":"2026-05-05T09:44:23.46401Z","steps":["trace[1294642386] 'agreement among raft nodes before linearized reading' (duration: 124.51955ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:23.950237Z","caller":"traceutil/trace.go:171","msg":"trace[78609792] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"111.685199ms","start":"2026-05-05T09:44:23.838528Z","end":"2026-05-05T09:44:23.950213Z","steps":["trace[78609792] 'read index received' (duration: 111.438863ms)","trace[78609792] 'applied index is now lower than readState.Index' (duration: 245.135µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:23.950295Z","caller":"traceutil/trace.go:171","msg":"trace[181969517] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"187.345064ms","start":"2026-05-05T09:44:23.762895Z","end":"2026-05-05T09:44:23.95024Z","steps":["trace[181969517] 'process raft request' (duration: 187.107589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:23.950584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.027157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-05T09:44:23.950668Z","caller":"traceutil/trace.go:171","msg":"trace[1954472574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2103; }","duration":"112.164069ms","start":"2026-05-05T09:44:23.838483Z","end":"2026-05-05T09:44:23.950647Z","steps":["trace[1954472574] 'agreement among raft nodes before linearized reading' (duration: 111.835742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:25.614064Z","caller":"traceutil/trace.go:171","msg":"trace[191719209] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"143.168768ms","start":"2026-05-05T09:44:25.470872Z","end":"2026-05-05T09:44:25.614041Z","steps":["trace[191719209] 'process raft request' (duration: 142.545655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:25.895162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.950831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:44:25.895356Z","caller":"traceutil/trace.go:171","msg":"trace[853892031] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"308.699464ms","start":"2026-05-05T09:44:25.586608Z","end":"2026-05-05T09:44:25.895308Z","steps":["trace[853892031] 'process raft request' (duration: 93.307844ms)","trace[853892031] 'compare' (duration: 214.638795ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:25.895442Z","caller":"traceutil/trace.go:171","msg":"trace[321598669] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"280.977344ms","start":"2026-05-05T09:44:25.614455Z","end":"2026-05-05T09:44:25.895432Z","steps":["trace[321598669] 'read index received' (duration: 65.479051ms)","trace[321598669] 'applied index is now lower than readState.Index' (duration: 215.497323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:25.895586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.687833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T09:44:25.895585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:25.586585Z","time spent":"308.870857ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:44:25.895618Z","caller":"traceutil/trace.go:171","msg":"trace[524432235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"338.762553ms","start":"2026-05-05T09:44:25.556848Z","end":"2026-05-05T09:44:25.895611Z","steps":["trace[524432235] 'agreement among raft nodes before linearized reading' (duration: 338.627151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:25.895648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:25.55683Z","time spent":"338.810995ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:44:25.895882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.680721ms","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-05-05T09:44:25.8954Z","caller":"traceutil/trace.go:171","msg":"trace[1649803898] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"276.819967ms","start":"2026-05-05T09:44:25.618559Z","end":"2026-05-05T09:44:25.895379Z","steps":["trace[1649803898] 'process raft request' (duration: 276.723495ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:25.895907Z","caller":"traceutil/trace.go:171","msg":"trace[1725696849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2109; }","duration":"338.738132ms","start":"2026-05-05T09:44:25.557162Z","end":"2026-05-05T09:44:25.8959Z","steps":["trace[1725696849] 'agreement among raft nodes before linearized reading' (duration: 338.660221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:25.895877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.011197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T09:44:25.895983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.414453ms","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":"info","ts":"2026-05-05T09:44:25.895996Z","caller":"traceutil/trace.go:171","msg":"trace[2037836147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2109; }","duration":"338.163152ms","start":"2026-05-05T09:44:25.557805Z","end":"2026-05-05T09:44:25.895968Z","steps":["trace[2037836147] 'agreement among raft nodes before linearized reading' (duration: 337.830564ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:25.896017Z","caller":"traceutil/trace.go:171","msg":"trace[1921447381] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2109; }","duration":"291.482405ms","start":"2026-05-05T09:44:25.604524Z","end":"2026-05-05T09:44:25.896007Z","steps":["trace[1921447381] 'agreement among raft nodes before linearized reading' (duration: 291.431913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:25.896058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:25.557795Z","time spent":"338.252943ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:44:25.895934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:25.557154Z","time spent":"338.775713ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:44:25.897148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.402974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T09:44:25.897259Z","caller":"traceutil/trace.go:171","msg":"trace[1089905333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2109; }","duration":"337.254842ms","start":"2026-05-05T09:44:25.559981Z","end":"2026-05-05T09:44:25.897236Z","steps":["trace[1089905333] 'agreement among raft nodes before linearized reading' (duration: 335.617777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:25.898157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:25.55997Z","time spent":"338.170321ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T09:45:39.771127Z","caller":"traceutil/trace.go:171","msg":"trace[1315909119] linearizableReadLoop","detail":"{readStateIndex:2703; appliedIndex:2702; }","duration":"107.46275ms","start":"2026-05-05T09:45:39.663641Z","end":"2026-05-05T09:45:39.771104Z","steps":["trace[1315909119] 'read index received' (duration: 107.273407ms)","trace[1315909119] 'applied index is now lower than readState.Index' (duration: 187.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:39.771201Z","caller":"traceutil/trace.go:171","msg":"trace[543085452] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"138.47675ms","start":"2026-05-05T09:45:39.632674Z","end":"2026-05-05T09:45:39.771151Z","steps":["trace[543085452] 'process raft request' (duration: 138.255637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:39.771336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.659612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:45:39.771373Z","caller":"traceutil/trace.go:171","msg":"trace[197105121] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2606; }","duration":"107.795604ms","start":"2026-05-05T09:45:39.663565Z","end":"2026-05-05T09:45:39.771361Z","steps":["trace[197105121] 'agreement among raft nodes before linearized reading' (duration: 107.693343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.721223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078867585216339597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T09:46:12.836868Z","caller":"traceutil/trace.go:171","msg":"trace[1039361771] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"846.431937ms","start":"2026-05-05T09:46:11.990409Z","end":"2026-05-05T09:46:12.836841Z","steps":["trace[1039361771] 'process raft request' (duration: 846.237234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.837157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:11.990395Z","time spent":"846.553078ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:46:12.856172Z","caller":"traceutil/trace.go:171","msg":"trace[2133237371] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2889; }","duration":"635.37318ms","start":"2026-05-05T09:46:12.220776Z","end":"2026-05-05T09:46:12.856149Z","steps":["trace[2133237371] 'read index received' (duration: 615.929656ms)","trace[2133237371] 'applied index is now lower than readState.Index' (duration: 19.442384ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:46:12.856405Z","caller":"traceutil/trace.go:171","msg":"trace[1135082998] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"548.290702ms","start":"2026-05-05T09:46:12.308042Z","end":"2026-05-05T09:46:12.856333Z","steps":["trace[1135082998] 'process raft request' (duration: 547.986597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.856596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.308009Z","time spent":"548.459334ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:46:12.856574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.738335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:46:12.856763Z","caller":"traceutil/trace.go:171","msg":"trace[1205929869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2786; }","duration":"636.291062ms","start":"2026-05-05T09:46:12.220446Z","end":"2026-05-05T09:46:12.856737Z","steps":["trace[1205929869] 'agreement among raft nodes before linearized reading' (duration: 635.929317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.856828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.220415Z","time spent":"636.400624ms","remote":"127.0.0.1:36738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T09:46:12.86858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.601193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T09:46:12.868655Z","caller":"traceutil/trace.go:171","msg":"trace[549995286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2787; }","duration":"452.721105ms","start":"2026-05-05T09:46:12.415915Z","end":"2026-05-05T09:46:12.868636Z","steps":["trace[549995286] 'agreement among raft nodes before linearized reading' (duration: 452.521761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.868695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.415893Z","time spent":"452.794815ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T09:46:12.868936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.00782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T09:46:12.868974Z","caller":"traceutil/trace.go:171","msg":"trace[836644584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2787; }","duration":"451.080971ms","start":"2026-05-05T09:46:12.417883Z","end":"2026-05-05T09:46:12.868964Z","steps":["trace[836644584] 'agreement among raft nodes before linearized reading' (duration: 450.99459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.417862Z","time spent":"451.131752ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T09:46:12.869018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.38791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T09:46:12.869122Z","caller":"traceutil/trace.go:171","msg":"trace[1274096199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2787; }","duration":"445.478351ms","start":"2026-05-05T09:46:12.423582Z","end":"2026-05-05T09:46:12.86906Z","steps":["trace[1274096199] 'agreement among raft nodes before linearized reading' (duration: 445.291539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.86917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.022234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T09:46:12.869296Z","caller":"traceutil/trace.go:171","msg":"trace[240154359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2787; }","duration":"442.258617ms","start":"2026-05-05T09:46:12.427022Z","end":"2026-05-05T09:46:12.869281Z","steps":["trace[240154359] 'agreement among raft nodes before linearized reading' (duration: 441.825461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.869363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.124457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T09:46:12.869429Z","caller":"traceutil/trace.go:171","msg":"trace[37444410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2787; }","duration":"445.250788ms","start":"2026-05-05T09:46:12.424168Z","end":"2026-05-05T09:46:12.869419Z","steps":["trace[37444410] 'agreement among raft nodes before linearized reading' (duration: 445.093726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:46:12.869467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.427005Z","time spent":"442.419108ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T09:46:12.869501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.42416Z","time spent":"445.332299ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T09:46:12.86959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.475604ms","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-05-05T09:46:12.869346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:46:12.42355Z","time spent":"445.776296ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T09:46:12.869724Z","caller":"traceutil/trace.go:171","msg":"trace[1989365051] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2787; }","duration":"219.671898ms","start":"2026-05-05T09:46:12.650025Z","end":"2026-05-05T09:46:12.869697Z","steps":["trace[1989365051] 'agreement among raft nodes before linearized reading' (duration: 219.308843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:46:18.258605Z","caller":"traceutil/trace.go:171","msg":"trace[1440794535] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"186.368102ms","start":"2026-05-05T09:46:18.072211Z","end":"2026-05-05T09:46:18.258579Z","steps":["trace[1440794535] 'process raft request' (duration: 186.17154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:39.536473Z","caller":"traceutil/trace.go:171","msg":"trace[1259109180] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"118.27381ms","start":"2026-05-05T09:47:39.418175Z","end":"2026-05-05T09:47:39.536448Z","steps":["trace[1259109180] 'process raft request' (duration: 118.193699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:39.536522Z","caller":"traceutil/trace.go:171","msg":"trace[116296856] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"119.510635ms","start":"2026-05-05T09:47:39.416974Z","end":"2026-05-05T09:47:39.536485Z","steps":["trace[116296856] 'process raft request' (duration: 119.194111ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:45.412237Z","caller":"traceutil/trace.go:171","msg":"trace[2137026510] transaction","detail":"{read_only:false; response_revision:3270; number_of_response:1; }","duration":"107.283444ms","start":"2026-05-05T09:47:45.304929Z","end":"2026-05-05T09:47:45.412212Z","steps":["trace[2137026510] 'process raft request' (duration: 107.142352ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:45.438791Z","caller":"traceutil/trace.go:171","msg":"trace[283378766] transaction","detail":"{read_only:false; response_revision:3271; number_of_response:1; }","duration":"121.950484ms","start":"2026-05-05T09:47:45.316824Z","end":"2026-05-05T09:47:45.438775Z","steps":["trace[283378766] 'process raft request' (duration: 121.860732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:47:47.715551Z","caller":"traceutil/trace.go:171","msg":"trace[759693032] transaction","detail":"{read_only:false; response_revision:3284; number_of_response:1; }","duration":"116.133117ms","start":"2026-05-05T09:47:47.59936Z","end":"2026-05-05T09:47:47.715493Z","steps":["trace[759693032] 'process raft request' (duration: 69.178391ms)","trace[759693032] 'compare' (duration: 46.577861ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:49:40.567003Z","caller":"traceutil/trace.go:171","msg":"trace[607945699] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"146.404445ms","start":"2026-05-05T09:49:40.420575Z","end":"2026-05-05T09:49:40.56698Z","steps":["trace[607945699] 'process raft request' (duration: 145.598312ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:49:40.566972Z","caller":"traceutil/trace.go:171","msg":"trace[1722889727] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"130.911723ms","start":"2026-05-05T09:49:40.43603Z","end":"2026-05-05T09:49:40.566941Z","steps":["trace[1722889727] 'process raft request' (duration: 130.845451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:00.55982Z","caller":"traceutil/trace.go:171","msg":"trace[2076485602] linearizableReadLoop","detail":"{readStateIndex:4699; appliedIndex:4698; }","duration":"249.559608ms","start":"2026-05-05T09:50:00.31023Z","end":"2026-05-05T09:50:00.55979Z","steps":["trace[2076485602] 'read index received' (duration: 208.170397ms)","trace[2076485602] 'applied index is now lower than readState.Index' (duration: 41.38811ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:00.559886Z","caller":"traceutil/trace.go:171","msg":"trace[213926315] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"257.559656ms","start":"2026-05-05T09:50:00.30229Z","end":"2026-05-05T09:50:00.55985Z","steps":["trace[213926315] 'process raft request' (duration: 216.095343ms)","trace[213926315] 'compare' (duration: 41.265568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:00.559991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.727933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:00.560036Z","caller":"traceutil/trace.go:171","msg":"trace[1632988546] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:0; response_revision:4525; }","duration":"249.813435ms","start":"2026-05-05T09:50:00.310209Z","end":"2026-05-05T09:50:00.560022Z","steps":["trace[1632988546] 'agreement among raft nodes before linearized reading' (duration: 249.719092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:00.561167Z","caller":"traceutil/trace.go:171","msg":"trace[1343227274] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"163.696231ms","start":"2026-05-05T09:50:00.397454Z","end":"2026-05-05T09:50:00.56115Z","steps":["trace[1343227274] 'process raft request' (duration: 163.403673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:00.561361Z","caller":"traceutil/trace.go:171","msg":"trace[1120051509] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"162.119259ms","start":"2026-05-05T09:50:00.399232Z","end":"2026-05-05T09:50:00.561351Z","steps":["trace[1120051509] 'process raft request' (duration: 161.854701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:00.561695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.608324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T09:50:00.561734Z","caller":"traceutil/trace.go:171","msg":"trace[1802075169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4527; }","duration":"109.703517ms","start":"2026-05-05T09:50:00.452021Z","end":"2026-05-05T09:50:00.561724Z","steps":["trace[1802075169] 'agreement among raft nodes before linearized reading' (duration: 109.568473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:00.561855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.607388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T09:50:00.561951Z","caller":"traceutil/trace.go:171","msg":"trace[547763343] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4527; }","duration":"171.770383ms","start":"2026-05-05T09:50:00.390154Z","end":"2026-05-05T09:50:00.561925Z","steps":["trace[547763343] 'agreement among raft nodes before linearized reading' (duration: 171.524575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:00.562042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.230961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T09:50:00.562088Z","caller":"traceutil/trace.go:171","msg":"trace[1843684518] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4527; }","duration":"174.324723ms","start":"2026-05-05T09:50:00.387753Z","end":"2026-05-05T09:50:00.562077Z","steps":["trace[1843684518] 'agreement among raft nodes before linearized reading' (duration: 173.568922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:05.614925Z","caller":"traceutil/trace.go:171","msg":"trace[758763515] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"157.135096ms","start":"2026-05-05T09:50:05.457718Z","end":"2026-05-05T09:50:05.614853Z","steps":["trace[758763515] 'process raft request' (duration: 156.825378ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:05.614981Z","caller":"traceutil/trace.go:171","msg":"trace[798911071] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4843; }","duration":"156.078808ms","start":"2026-05-05T09:50:05.458676Z","end":"2026-05-05T09:50:05.614755Z","steps":["trace[798911071] 'read index received' (duration: 155.732169ms)","trace[798911071] 'applied index is now lower than readState.Index' (duration: 345.379µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:05.61502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.315754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:05.615094Z","caller":"traceutil/trace.go:171","msg":"trace[1366609555] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4667; }","duration":"156.417547ms","start":"2026-05-05T09:50:05.458662Z","end":"2026-05-05T09:50:05.61508Z","steps":["trace[1366609555] 'agreement among raft nodes before linearized reading' (duration: 156.215152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.615227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.403549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T09:50:05.615304Z","caller":"traceutil/trace.go:171","msg":"trace[306607043] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:4667; }","duration":"139.560773ms","start":"2026-05-05T09:50:05.475735Z","end":"2026-05-05T09:50:05.615296Z","steps":["trace[306607043] 'agreement among raft nodes before linearized reading' (duration: 139.321017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.91755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.811497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:05.917809Z","caller":"traceutil/trace.go:171","msg":"trace[1166531903] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"221.654141ms","start":"2026-05-05T09:50:05.696104Z","end":"2026-05-05T09:50:05.917758Z","steps":["trace[1166531903] 'process raft request' (duration: 109.556497ms)","trace[1166531903] 'compare' (duration: 111.541259ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:05.917831Z","caller":"traceutil/trace.go:171","msg":"trace[1000008554] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4854; }","duration":"221.507767ms","start":"2026-05-05T09:50:05.696307Z","end":"2026-05-05T09:50:05.917814Z","steps":["trace[1000008554] 'read index received' (duration: 7.41795ms)","trace[1000008554] 'applied index is now lower than readState.Index' (duration: 214.088957ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:05.917949Z","caller":"traceutil/trace.go:171","msg":"trace[1690394578] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"216.79758ms","start":"2026-05-05T09:50:05.70109Z","end":"2026-05-05T09:50:05.917888Z","steps":["trace[1690394578] 'process raft request' (duration: 216.594464ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:05.918079Z","caller":"traceutil/trace.go:171","msg":"trace[2141961431] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"216.079181ms","start":"2026-05-05T09:50:05.701987Z","end":"2026-05-05T09:50:05.918066Z","steps":["trace[2141961431] 'process raft request' (duration: 215.786254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.918629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.277519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11453"} {"level":"info","ts":"2026-05-05T09:50:05.91869Z","caller":"traceutil/trace.go:171","msg":"trace[1380535370] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4680; }","duration":"222.402491ms","start":"2026-05-05T09:50:05.696272Z","end":"2026-05-05T09:50:05.918674Z","steps":["trace[1380535370] 'agreement among raft nodes before linearized reading' (duration: 221.97085ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:05.920301Z","caller":"traceutil/trace.go:171","msg":"trace[1716606094] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"212.080272ms","start":"2026-05-05T09:50:05.708209Z","end":"2026-05-05T09:50:05.920289Z","steps":["trace[1716606094] 'process raft request' (duration: 211.929268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.922478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.168099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-05T09:50:05.922513Z","caller":"traceutil/trace.go:171","msg":"trace[1920068600] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4682; }","duration":"224.22326ms","start":"2026-05-05T09:50:05.698281Z","end":"2026-05-05T09:50:05.922504Z","steps":["trace[1920068600] 'agreement among raft nodes before linearized reading' (duration: 224.155658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.922694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.601234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T09:50:05.922719Z","caller":"traceutil/trace.go:171","msg":"trace[1439536379] 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:4682; }","duration":"200.626954ms","start":"2026-05-05T09:50:05.722084Z","end":"2026-05-05T09:50:05.922711Z","steps":["trace[1439536379] 'agreement among raft nodes before linearized reading' (duration: 200.557802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.922854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.983353ms","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-05-05T09:50:05.923014Z","caller":"traceutil/trace.go:171","msg":"trace[1365652860] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4682; }","duration":"195.209389ms","start":"2026-05-05T09:50:05.727785Z","end":"2026-05-05T09:50:05.922994Z","steps":["trace[1365652860] 'agreement among raft nodes before linearized reading' (duration: 194.844449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:05.922919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.986353ms","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-05-05T09:50:05.923756Z","caller":"traceutil/trace.go:171","msg":"trace[1865488196] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4682; }","duration":"221.834997ms","start":"2026-05-05T09:50:05.701911Z","end":"2026-05-05T09:50:05.923746Z","steps":["trace[1865488196] 'agreement among raft nodes before linearized reading' (duration: 220.927342ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:05.922922Z","caller":"traceutil/trace.go:171","msg":"trace[1675840186] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"110.105761ms","start":"2026-05-05T09:50:05.81281Z","end":"2026-05-05T09:50:05.922916Z","steps":["trace[1675840186] 'process raft request' (duration: 109.562306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:07.089683Z","caller":"traceutil/trace.go:171","msg":"trace[758994076] linearizableReadLoop","detail":"{readStateIndex:4884; appliedIndex:4883; }","duration":"398.9324ms","start":"2026-05-05T09:50:06.690729Z","end":"2026-05-05T09:50:07.089662Z","steps":["trace[758994076] 'read index received' (duration: 398.773825ms)","trace[758994076] 'applied index is now lower than readState.Index' (duration: 157.435µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:07.089879Z","caller":"traceutil/trace.go:171","msg":"trace[2135550796] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"429.509289ms","start":"2026-05-05T09:50:06.66035Z","end":"2026-05-05T09:50:07.089859Z","steps":["trace[2135550796] 'process raft request' (duration: 429.17233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.090132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.66033Z","time spent":"429.611292ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:07.090145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.393732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T09:50:07.090324Z","caller":"traceutil/trace.go:171","msg":"trace[1664402673] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4705; }","duration":"399.609427ms","start":"2026-05-05T09:50:06.690695Z","end":"2026-05-05T09:50:07.090305Z","steps":["trace[1664402673] 'agreement among raft nodes before linearized reading' (duration: 399.299038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.090439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.690677Z","time spent":"399.748601ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T09:50:07.102726Z","caller":"traceutil/trace.go:171","msg":"trace[1392906251] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"369.465398ms","start":"2026-05-05T09:50:06.733247Z","end":"2026-05-05T09:50:07.102712Z","steps":["trace[1392906251] 'process raft request' (duration: 369.378025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.102918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.21409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:50:07.10298Z","caller":"traceutil/trace.go:171","msg":"trace[1090048077] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4706; }","duration":"200.320442ms","start":"2026-05-05T09:50:06.902646Z","end":"2026-05-05T09:50:07.102966Z","steps":["trace[1090048077] 'agreement among raft nodes before linearized reading' (duration: 200.170328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.103125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.236872ms","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-05-05T09:50:07.10325Z","caller":"traceutil/trace.go:171","msg":"trace[809695296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4706; }","duration":"403.383286ms","start":"2026-05-05T09:50:06.699846Z","end":"2026-05-05T09:50:07.10323Z","steps":["trace[809695296] 'agreement among raft nodes before linearized reading' (duration: 402.864782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.103154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.782036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T09:50:07.103345Z","caller":"traceutil/trace.go:171","msg":"trace[1038494158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4706; }","duration":"395.041613ms","start":"2026-05-05T09:50:06.708293Z","end":"2026-05-05T09:50:07.103335Z","steps":["trace[1038494158] 'agreement among raft nodes before linearized reading' (duration: 394.58596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.103379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.708285Z","time spent":"395.084644ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T09:50:07.103392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.535261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-lh4x6\" ","response":"range_response_count:1 size:5150"} {"level":"warn","ts":"2026-05-05T09:50:07.103409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.946072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194894"} {"level":"info","ts":"2026-05-05T09:50:07.103439Z","caller":"traceutil/trace.go:171","msg":"trace[1140903294] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4706; }","duration":"241.016534ms","start":"2026-05-05T09:50:06.862415Z","end":"2026-05-05T09:50:07.103432Z","steps":["trace[1140903294] 'agreement among raft nodes before linearized reading' (duration: 240.363315ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:07.103446Z","caller":"traceutil/trace.go:171","msg":"trace[284924515] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-lh4x6; range_end:; response_count:1; response_revision:4706; }","duration":"312.624284ms","start":"2026-05-05T09:50:06.790804Z","end":"2026-05-05T09:50:07.103429Z","steps":["trace[284924515] 'agreement among raft nodes before linearized reading' (duration: 311.973275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:07.103505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.699835Z","time spent":"403.614793ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T09:50:07.103492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.790785Z","time spent":"312.693886ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5172,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-lh4x6\" "} {"level":"warn","ts":"2026-05-05T09:50:07.103812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:06.73323Z","time spent":"369.531369ms","remote":"127.0.0.1:47826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:50:12.826744Z","caller":"traceutil/trace.go:171","msg":"trace[222851703] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"227.137648ms","start":"2026-05-05T09:50:12.599581Z","end":"2026-05-05T09:50:12.826719Z","steps":["trace[222851703] 'process raft request' (duration: 187.713641ms)","trace[222851703] 'compare' (duration: 39.051788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:12.827004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.96199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T09:50:12.826744Z","caller":"traceutil/trace.go:171","msg":"trace[1500914843] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4999; }","duration":"181.691083ms","start":"2026-05-05T09:50:12.64503Z","end":"2026-05-05T09:50:12.826721Z","steps":["trace[1500914843] 'read index received' (duration: 142.241234ms)","trace[1500914843] 'applied index is now lower than readState.Index' (duration: 39.445348ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:12.827045Z","caller":"traceutil/trace.go:171","msg":"trace[1793375637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4819; }","duration":"182.039842ms","start":"2026-05-05T09:50:12.644994Z","end":"2026-05-05T09:50:12.827034Z","steps":["trace[1793375637] 'agreement among raft nodes before linearized reading' (duration: 181.816586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:12.827266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.292043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T09:50:12.827346Z","caller":"traceutil/trace.go:171","msg":"trace[97220819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4819; }","duration":"178.409376ms","start":"2026-05-05T09:50:12.648921Z","end":"2026-05-05T09:50:12.82733Z","steps":["trace[97220819] 'agreement among raft nodes before linearized reading' (duration: 178.233022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:13.226961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.363617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:13.227222Z","caller":"traceutil/trace.go:171","msg":"trace[664436979] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"395.799532ms","start":"2026-05-05T09:50:12.831394Z","end":"2026-05-05T09:50:13.227193Z","steps":["trace[664436979] 'process raft request' (duration: 288.105395ms)","trace[664436979] 'compare' (duration: 107.267745ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:13.227381Z","caller":"traceutil/trace.go:171","msg":"trace[119356381] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"395.900664ms","start":"2026-05-05T09:50:12.831462Z","end":"2026-05-05T09:50:13.227363Z","steps":["trace[119356381] 'process raft request' (duration: 395.641858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:13.227446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:12.831379Z","time spent":"395.988447ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:13.227592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:12.831441Z","time spent":"396.041689ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:13.673067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.452661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:13.673173Z","caller":"traceutil/trace.go:171","msg":"trace[767591185] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"250.571586ms","start":"2026-05-05T09:50:13.422581Z","end":"2026-05-05T09:50:13.673153Z","steps":["trace[767591185] 'process raft request' (duration: 49.962261ms)","trace[767591185] 'compare' (duration: 200.242336ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:13.677362Z","caller":"traceutil/trace.go:171","msg":"trace[359405566] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"239.556886ms","start":"2026-05-05T09:50:13.43773Z","end":"2026-05-05T09:50:13.677286Z","steps":["trace[359405566] 'process raft request' (duration: 239.285829ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:13.677868Z","caller":"traceutil/trace.go:171","msg":"trace[170573141] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"238.417077ms","start":"2026-05-05T09:50:13.439422Z","end":"2026-05-05T09:50:13.677839Z","steps":["trace[170573141] 'process raft request' (duration: 237.790641ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:13.67823Z","caller":"traceutil/trace.go:171","msg":"trace[1480865353] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"238.637383ms","start":"2026-05-05T09:50:13.439535Z","end":"2026-05-05T09:50:13.678173Z","steps":["trace[1480865353] 'process raft request' (duration: 238.524221ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:13.678228Z","caller":"traceutil/trace.go:171","msg":"trace[1664290415] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"238.689965ms","start":"2026-05-05T09:50:13.43948Z","end":"2026-05-05T09:50:13.67817Z","steps":["trace[1664290415] 'process raft request' (duration: 238.235723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:21.558429Z","caller":"traceutil/trace.go:171","msg":"trace[201802547] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"195.330337ms","start":"2026-05-05T09:50:21.363065Z","end":"2026-05-05T09:50:21.558395Z","steps":["trace[201802547] 'process raft request' (duration: 192.33696ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:21.559727Z","caller":"traceutil/trace.go:171","msg":"trace[2003304393] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"195.002849ms","start":"2026-05-05T09:50:21.364697Z","end":"2026-05-05T09:50:21.559699Z","steps":["trace[2003304393] 'process raft request' (duration: 194.746853ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:21.5597Z","caller":"traceutil/trace.go:171","msg":"trace[1366676983] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5131; }","duration":"191.092638ms","start":"2026-05-05T09:50:21.368567Z","end":"2026-05-05T09:50:21.55966Z","steps":["trace[1366676983] 'read index received' (duration: 186.848269ms)","trace[1366676983] 'applied index is now lower than readState.Index' (duration: 4.242609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:21.560034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.306191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-05-05T09:50:21.560066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.452057ms","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-05-05T09:50:21.560105Z","caller":"traceutil/trace.go:171","msg":"trace[2110603643] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4947; }","duration":"171.419664ms","start":"2026-05-05T09:50:21.388664Z","end":"2026-05-05T09:50:21.560084Z","steps":["trace[2110603643] 'agreement among raft nodes before linearized reading' (duration: 171.219939ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:21.560118Z","caller":"traceutil/trace.go:171","msg":"trace[1271595104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4947; }","duration":"191.593171ms","start":"2026-05-05T09:50:21.368504Z","end":"2026-05-05T09:50:21.560097Z","steps":["trace[1271595104] 'agreement among raft nodes before linearized reading' (duration: 191.349995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:21.560279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.258489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17233"} {"level":"info","ts":"2026-05-05T09:50:21.560353Z","caller":"traceutil/trace.go:171","msg":"trace[2095459470] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4947; }","duration":"146.377702ms","start":"2026-05-05T09:50:21.413953Z","end":"2026-05-05T09:50:21.560331Z","steps":["trace[2095459470] 'agreement among raft nodes before linearized reading' (duration: 146.219568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:26.54058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.93512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:26.541061Z","caller":"traceutil/trace.go:171","msg":"trace[189137993] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"490.129516ms","start":"2026-05-05T09:50:26.050906Z","end":"2026-05-05T09:50:26.541036Z","steps":["trace[189137993] 'process raft request' (duration: 337.648284ms)","trace[189137993] 'compare' (duration: 151.761505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:26.541151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:26.05089Z","time spent":"490.213818ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:50:26.63787Z","caller":"traceutil/trace.go:171","msg":"trace[1502757040] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"490.245859ms","start":"2026-05-05T09:50:26.147603Z","end":"2026-05-05T09:50:26.637849Z","steps":["trace[1502757040] 'process raft request' (duration: 490.101276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:26.638012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:26.147572Z","time spent":"490.359932ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:50:26.638112Z","caller":"traceutil/trace.go:171","msg":"trace[340384538] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"477.082786ms","start":"2026-05-05T09:50:26.161002Z","end":"2026-05-05T09:50:26.638085Z","steps":["trace[340384538] 'process raft request' (duration: 476.81053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:26.638247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.427694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:50:26.638268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:26.160985Z","time spent":"477.212369ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:50:26.638294Z","caller":"traceutil/trace.go:171","msg":"trace[1996530254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4993; }","duration":"418.516686ms","start":"2026-05-05T09:50:26.219765Z","end":"2026-05-05T09:50:26.638282Z","steps":["trace[1996530254] 'agreement among raft nodes before linearized reading' (duration: 418.396683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:26.638331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:26.219753Z","time spent":"418.565237ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:50:26.638064Z","caller":"traceutil/trace.go:171","msg":"trace[195515915] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5177; }","duration":"418.235339ms","start":"2026-05-05T09:50:26.219801Z","end":"2026-05-05T09:50:26.638036Z","steps":["trace[195515915] 'read index received' (duration: 168.863787ms)","trace[195515915] 'applied index is now lower than readState.Index' (duration: 249.365332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:26.653159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.839413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T09:50:26.653217Z","caller":"traceutil/trace.go:171","msg":"trace[99730092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4994; }","duration":"226.899194ms","start":"2026-05-05T09:50:26.426303Z","end":"2026-05-05T09:50:26.653203Z","steps":["trace[99730092] 'agreement among raft nodes before linearized reading' (duration: 226.73631ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:26.796436Z","caller":"traceutil/trace.go:171","msg":"trace[834271954] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"131.290128ms","start":"2026-05-05T09:50:26.66512Z","end":"2026-05-05T09:50:26.79641Z","steps":["trace[834271954] 'process raft request' (duration: 131.057963ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:26.998389Z","caller":"traceutil/trace.go:171","msg":"trace[1697906225] linearizableReadLoop","detail":"{readStateIndex:5189; appliedIndex:5187; }","duration":"142.868481ms","start":"2026-05-05T09:50:26.855495Z","end":"2026-05-05T09:50:26.998364Z","steps":["trace[1697906225] 'read index received' (duration: 5.52034ms)","trace[1697906225] 'applied index is now lower than readState.Index' (duration: 137.347041ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:26.998502Z","caller":"traceutil/trace.go:171","msg":"trace[913203400] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"144.06664ms","start":"2026-05-05T09:50:26.854402Z","end":"2026-05-05T09:50:26.998468Z","steps":["trace[913203400] 'process raft request' (duration: 104.094571ms)","trace[913203400] 'compare' (duration: 39.629041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:26.998799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.281292ms","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-05-05T09:50:26.998848Z","caller":"traceutil/trace.go:171","msg":"trace[418583840] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5002; }","duration":"143.353684ms","start":"2026-05-05T09:50:26.855482Z","end":"2026-05-05T09:50:26.998836Z","steps":["trace[418583840] 'agreement among raft nodes before linearized reading' (duration: 143.002574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.006053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.89889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:50:27.006128Z","caller":"traceutil/trace.go:171","msg":"trace[2145769847] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5003; }","duration":"105.002002ms","start":"2026-05-05T09:50:26.901104Z","end":"2026-05-05T09:50:27.006106Z","steps":["trace[2145769847] 'agreement among raft nodes before linearized reading' (duration: 104.885159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:27.006266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.220099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18aca27d42773709\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-05-05T09:50:27.006348Z","caller":"traceutil/trace.go:171","msg":"trace[778569705] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18aca27d42773709; range_end:; response_count:1; response_revision:5003; }","duration":"143.321231ms","start":"2026-05-05T09:50:26.863007Z","end":"2026-05-05T09:50:27.006328Z","steps":["trace[778569705] 'agreement among raft nodes before linearized reading' (duration: 143.116746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:53.942697Z","caller":"traceutil/trace.go:171","msg":"trace[1914301616] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"181.405381ms","start":"2026-05-05T09:50:53.761258Z","end":"2026-05-05T09:50:53.942664Z","steps":["trace[1914301616] 'process raft request' (duration: 181.237997ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:53.946501Z","caller":"traceutil/trace.go:171","msg":"trace[851785734] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"182.053235ms","start":"2026-05-05T09:50:53.764431Z","end":"2026-05-05T09:50:53.946485Z","steps":["trace[851785734] 'process raft request' (duration: 181.920762ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:53.946705Z","caller":"traceutil/trace.go:171","msg":"trace[1371517292] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"181.708268ms","start":"2026-05-05T09:50:53.764971Z","end":"2026-05-05T09:50:53.946679Z","steps":["trace[1371517292] 'process raft request' (duration: 181.470622ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:55.69894Z","caller":"traceutil/trace.go:171","msg":"trace[424403690] linearizableReadLoop","detail":"{readStateIndex:5473; appliedIndex:5472; }","duration":"206.535019ms","start":"2026-05-05T09:50:55.492388Z","end":"2026-05-05T09:50:55.698923Z","steps":["trace[424403690] 'read index received' (duration: 206.387956ms)","trace[424403690] 'applied index is now lower than readState.Index' (duration: 146.183µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:55.699093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.696813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T09:50:55.699114Z","caller":"traceutil/trace.go:171","msg":"trace[1557437434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5279; }","duration":"206.752595ms","start":"2026-05-05T09:50:55.492354Z","end":"2026-05-05T09:50:55.699106Z","steps":["trace[1557437434] 'agreement among raft nodes before linearized reading' (duration: 206.653112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:55.699243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.368868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T09:50:55.699327Z","caller":"traceutil/trace.go:171","msg":"trace[1491958289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5279; }","duration":"198.511091ms","start":"2026-05-05T09:50:55.500796Z","end":"2026-05-05T09:50:55.699307Z","steps":["trace[1491958289] 'agreement among raft nodes before linearized reading' (duration: 198.267316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:55.908552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.420644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:55.908671Z","caller":"traceutil/trace.go:171","msg":"trace[704248136] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"208.473174ms","start":"2026-05-05T09:50:55.700179Z","end":"2026-05-05T09:50:55.908652Z","steps":["trace[704248136] 'process raft request' (duration: 98.852376ms)","trace[704248136] 'compare' (duration: 109.293801ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:55.938285Z","caller":"traceutil/trace.go:171","msg":"trace[486398852] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"234.946434ms","start":"2026-05-05T09:50:55.703301Z","end":"2026-05-05T09:50:55.938248Z","steps":["trace[486398852] 'process raft request' (duration: 234.311989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:55.938445Z","caller":"traceutil/trace.go:171","msg":"trace[486542523] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"235.18028ms","start":"2026-05-05T09:50:55.703234Z","end":"2026-05-05T09:50:55.938414Z","steps":["trace[486542523] 'process raft request' (duration: 234.219838ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:32.550249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2890} {"level":"info","ts":"2026-05-05T09:51:32.630075Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2890,"took":"78.18022ms","hash":3288120472,"current-db-size-bytes":52768768,"current-db-size":"53 MB","current-db-size-in-use-bytes":28639232,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T09:51:32.63015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3288120472,"revision":2890,"compact-revision":-1} {"level":"info","ts":"2026-05-05T09:51:35.979697Z","caller":"traceutil/trace.go:171","msg":"trace[710946613] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"225.21824ms","start":"2026-05-05T09:51:35.754452Z","end":"2026-05-05T09:51:35.97967Z","steps":["trace[710946613] 'process raft request' (duration: 225.004554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:54:09.128243Z","caller":"traceutil/trace.go:171","msg":"trace[1605379835] linearizableReadLoop","detail":"{readStateIndex:6844; appliedIndex:6842; }","duration":"120.038026ms","start":"2026-05-05T09:54:09.00818Z","end":"2026-05-05T09:54:09.128218Z","steps":["trace[1605379835] 'read index received' (duration: 19.08881ms)","trace[1605379835] 'applied index is now lower than readState.Index' (duration: 100.948076ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:54:09.128274Z","caller":"traceutil/trace.go:171","msg":"trace[893685866] transaction","detail":"{read_only:false; response_revision:6603; number_of_response:1; }","duration":"123.020689ms","start":"2026-05-05T09:54:09.005214Z","end":"2026-05-05T09:54:09.128235Z","steps":["trace[893685866] 'process raft request' (duration: 30.868269ms)","trace[893685866] 'compare' (duration: 91.952847ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:54:09.128376Z","caller":"traceutil/trace.go:171","msg":"trace[1075965779] transaction","detail":"{read_only:false; response_revision:6604; number_of_response:1; }","duration":"123.10449ms","start":"2026-05-05T09:54:09.005259Z","end":"2026-05-05T09:54:09.128363Z","steps":["trace[1075965779] 'process raft request' (duration: 122.910177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:54:09.128607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.998172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T09:54:09.128651Z","caller":"traceutil/trace.go:171","msg":"trace[1646833766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6604; }","duration":"103.080204ms","start":"2026-05-05T09:54:09.025559Z","end":"2026-05-05T09:54:09.128639Z","steps":["trace[1646833766] 'agreement among raft nodes before linearized reading' (duration: 102.876551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:54:09.128662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.462043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T09:54:09.128701Z","caller":"traceutil/trace.go:171","msg":"trace[1017935474] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6604; }","duration":"120.535065ms","start":"2026-05-05T09:54:09.008151Z","end":"2026-05-05T09:54:09.128686Z","steps":["trace[1017935474] 'agreement among raft nodes before linearized reading' (duration: 120.397353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:54:26.133672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.240145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:54:26.133736Z","caller":"traceutil/trace.go:171","msg":"trace[904824143] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6746; }","duration":"149.343678ms","start":"2026-05-05T09:54:25.984378Z","end":"2026-05-05T09:54:26.133721Z","steps":["trace[904824143] 'range keys from in-memory index tree' (duration: 149.182844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:54:26.133754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107263"} {"level":"info","ts":"2026-05-05T09:54:26.133862Z","caller":"traceutil/trace.go:171","msg":"trace[1334711972] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6746; }","duration":"161.140195ms","start":"2026-05-05T09:54:25.9727Z","end":"2026-05-05T09:54:26.13384Z","steps":["trace[1334711972] 'range keys from in-memory index tree' (duration: 160.730988ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:56:32.557422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5557} {"level":"info","ts":"2026-05-05T09:56:32.634758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5557,"took":"75.659325ms","hash":2667096623,"current-db-size-bytes":52768768,"current-db-size":"53 MB","current-db-size-in-use-bytes":18173952,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T09:56:32.634814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2667096623,"revision":5557,"compact-revision":2890}