{"level":"warn","ts":"2026-05-13T15:36:50.234228Z","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-13T15:36:50.234326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-13T15:36:50.234396Z","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-13T15:36:50.234406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-13T15:36:50.234423Z","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-13T15:36:50.235172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-13T15:36:50.23526Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-13T15:36:50.239207Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.72665ms"} {"level":"info","ts":"2026-05-13T15:36:50.250538Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-13T15:36:50.250609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-13T15:36:50.250632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-13T15:36:50.250643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-13T15:36:50.250654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-13T15:36:50.25069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-13T15:36:50.256504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-13T15:36:50.257929Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-13T15:36:50.259359Z","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-13T15:36:50.262536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-13T15:36:50.263074Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-13T15:36:50.263137Z","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-13T15:36:50.263745Z","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-13T15:36:50.263887Z","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-13T15:36:50.265031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-13T15:36:50.26529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-13T15:36:50.26745Z","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-13T15:36:50.267561Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-13T15:36:50.267616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-13T15:36:50.26805Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-13T15:36:50.268135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-13T15:36:50.551709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-13T15:36:50.551781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-13T15:36:50.551803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-13T15:36:50.551822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-13T15:36:50.551833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-13T15:36:50.551849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-13T15:36:50.551863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-13T15:36:50.552936Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T15:36:50.553798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T15:36:50.553801Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-13T15:36:50.553843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T15:36:50.554122Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-13T15:36:50.554166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-13T15:36:50.558127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-13T15:36:50.558404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T15:36:50.558235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-13T15:36:50.558551Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T15:36:50.558591Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T15:37:00.251655Z","caller":"traceutil/trace.go:171","msg":"trace[64905049] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"110.167763ms","start":"2026-05-13T15:37:00.141456Z","end":"2026-05-13T15:37:00.251624Z","steps":["trace[64905049] 'process raft request' (duration: 103.702074ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:05.989811Z","caller":"traceutil/trace.go:171","msg":"trace[1324330260] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"162.587554ms","start":"2026-05-13T15:37:05.827181Z","end":"2026-05-13T15:37:05.989769Z","steps":["trace[1324330260] 'process raft request' (duration: 162.312508ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:21.978615Z","caller":"traceutil/trace.go:171","msg":"trace[764102573] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"110.272749ms","start":"2026-05-13T15:37:21.868313Z","end":"2026-05-13T15:37:21.978586Z","steps":["trace[764102573] 'process raft request' (duration: 35.637061ms)","trace[764102573] 'compare' (duration: 74.375692ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:37:21.979107Z","caller":"traceutil/trace.go:171","msg":"trace[530101581] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"106.577968ms","start":"2026-05-13T15:37:21.872514Z","end":"2026-05-13T15:37:21.979092Z","steps":["trace[530101581] 'process raft request' (duration: 106.012946ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:22.701646Z","caller":"traceutil/trace.go:171","msg":"trace[947741600] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"254.416341ms","start":"2026-05-13T15:37:22.447199Z","end":"2026-05-13T15:37:22.701615Z","steps":["trace[947741600] 'process raft request' (duration: 254.224917ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:22.711258Z","caller":"traceutil/trace.go:171","msg":"trace[980510448] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"256.899716ms","start":"2026-05-13T15:37:22.454336Z","end":"2026-05-13T15:37:22.711235Z","steps":["trace[980510448] 'process raft request' (duration: 256.66762ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:22.711946Z","caller":"traceutil/trace.go:171","msg":"trace[477407415] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"208.803084ms","start":"2026-05-13T15:37:22.503128Z","end":"2026-05-13T15:37:22.711931Z","steps":["trace[477407415] 'read index received' (duration: 198.330986ms)","trace[477407415] 'applied index is now lower than readState.Index' (duration: 10.471448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:37:22.712122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.989297ms","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-13T15:37:22.712174Z","caller":"traceutil/trace.go:171","msg":"trace[916056780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"209.09854ms","start":"2026-05-13T15:37:22.503067Z","end":"2026-05-13T15:37:22.712166Z","steps":["trace[916056780] 'agreement among raft nodes before linearized reading' (duration: 208.936886ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:27.958683Z","caller":"traceutil/trace.go:171","msg":"trace[140784964] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"125.024018ms","start":"2026-05-13T15:37:27.833611Z","end":"2026-05-13T15:37:27.958635Z","steps":["trace[140784964] 'process raft request' (duration: 124.20023ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:37:28.26217Z","caller":"traceutil/trace.go:171","msg":"trace[1934343729] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"243.360932ms","start":"2026-05-13T15:37:28.018744Z","end":"2026-05-13T15:37:28.262105Z","steps":["trace[1934343729] 'process raft request' (duration: 196.66161ms)","trace[1934343729] 'compare' (duration: 46.100489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:37:30.178144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.855226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T15:37:30.178239Z","caller":"traceutil/trace.go:171","msg":"trace[301351860] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:617; }","duration":"101.996929ms","start":"2026-05-13T15:37:30.076222Z","end":"2026-05-13T15:37:30.178219Z","steps":["trace[301351860] 'range keys from in-memory index tree' (duration: 101.778344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:37:30.178571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.03015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T15:37:30.178641Z","caller":"traceutil/trace.go:171","msg":"trace[120030440] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:617; }","duration":"102.138862ms","start":"2026-05-13T15:37:30.076485Z","end":"2026-05-13T15:37:30.178624Z","steps":["trace[120030440] 'count revisions from in-memory index tree' (duration: 101.965288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:37:30.178676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.938298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T15:37:30.178785Z","caller":"traceutil/trace.go:171","msg":"trace[1865157039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:617; }","duration":"102.089791ms","start":"2026-05-13T15:37:30.076671Z","end":"2026-05-13T15:37:30.178761Z","steps":["trace[1865157039] 'range keys from in-memory index tree' (duration: 101.891317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:37:43.25109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.980491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k4qbv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-13T15:37:43.251174Z","caller":"traceutil/trace.go:171","msg":"trace[1892813595] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k4qbv; range_end:; response_count:1; response_revision:740; }","duration":"230.118464ms","start":"2026-05-13T15:37:43.021038Z","end":"2026-05-13T15:37:43.251156Z","steps":["trace[1892813595] 'range keys from in-memory index tree' (duration: 229.668064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:37:43.251487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.552371ms","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-13T15:37:43.251576Z","caller":"traceutil/trace.go:171","msg":"trace[2068118117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"132.691924ms","start":"2026-05-13T15:37:43.118865Z","end":"2026-05-13T15:37:43.251556Z","steps":["trace[2068118117] 'range keys from in-memory index tree' (duration: 132.359177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:37:43.251597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.652816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-13T15:37:43.251685Z","caller":"traceutil/trace.go:171","msg":"trace[1157267996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"128.775789ms","start":"2026-05-13T15:37:43.122887Z","end":"2026-05-13T15:37:43.251663Z","steps":["trace[1157267996] 'range keys from in-memory index tree' (duration: 128.38393ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:38:32.445493Z","caller":"traceutil/trace.go:171","msg":"trace[1317751980] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"121.799048ms","start":"2026-05-13T15:38:32.323667Z","end":"2026-05-13T15:38:32.445466Z","steps":["trace[1317751980] 'process raft request' (duration: 121.641035ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:38:57.550851Z","caller":"traceutil/trace.go:171","msg":"trace[541167217] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"277.317209ms","start":"2026-05-13T15:38:57.273508Z","end":"2026-05-13T15:38:57.550825Z","steps":["trace[541167217] 'read index received' (duration: 277.140615ms)","trace[541167217] 'applied index is now lower than readState.Index' (duration: 175.494µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:38:57.551043Z","caller":"traceutil/trace.go:171","msg":"trace[1613788883] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"284.757211ms","start":"2026-05-13T15:38:57.266273Z","end":"2026-05-13T15:38:57.551031Z","steps":["trace[1613788883] 'process raft request' (duration: 284.392453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:38:57.551298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.780398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T15:38:57.551427Z","caller":"traceutil/trace.go:171","msg":"trace[399459820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1812; }","duration":"277.894431ms","start":"2026-05-13T15:38:57.273446Z","end":"2026-05-13T15:38:57.551341Z","steps":["trace[399459820] 'agreement among raft nodes before linearized reading' (duration: 277.736617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:38:57.551538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.441834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-406478ce-114e-4dec-b61b-5460a3077374\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-13T15:38:57.551619Z","caller":"traceutil/trace.go:171","msg":"trace[1024538513] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-406478ce-114e-4dec-b61b-5460a3077374; range_end:; response_count:1; response_revision:1812; }","duration":"123.571257ms","start":"2026-05-13T15:38:57.428028Z","end":"2026-05-13T15:38:57.5516Z","steps":["trace[1024538513] 'agreement among raft nodes before linearized reading' (duration: 123.287501ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:38:57.939712Z","caller":"traceutil/trace.go:171","msg":"trace[609495421] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"243.196848ms","start":"2026-05-13T15:38:57.696476Z","end":"2026-05-13T15:38:57.939673Z","steps":["trace[609495421] 'process raft request' (duration: 242.073544ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:38:57.939916Z","caller":"traceutil/trace.go:171","msg":"trace[946916417] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"240.685513ms","start":"2026-05-13T15:38:57.699123Z","end":"2026-05-13T15:38:57.939808Z","steps":["trace[946916417] 'read index received' (duration: 239.414315ms)","trace[946916417] 'applied index is now lower than readState.Index' (duration: 1.270108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:38:57.940773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.617763ms","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-13T15:38:57.940834Z","caller":"traceutil/trace.go:171","msg":"trace[109636094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"241.712156ms","start":"2026-05-13T15:38:57.699095Z","end":"2026-05-13T15:38:57.940808Z","steps":["trace[109636094] 'agreement among raft nodes before linearized reading' (duration: 241.210995ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:38:57.94095Z","caller":"traceutil/trace.go:171","msg":"trace[2120358365] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"219.954167ms","start":"2026-05-13T15:38:57.720576Z","end":"2026-05-13T15:38:57.94053Z","steps":["trace[2120358365] 'process raft request' (duration: 219.116288ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:12.744798Z","caller":"traceutil/trace.go:171","msg":"trace[1567341081] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"104.659491ms","start":"2026-05-13T15:39:12.64011Z","end":"2026-05-13T15:39:12.74477Z","steps":["trace[1567341081] 'process raft request' (duration: 104.514988ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:13.030302Z","caller":"traceutil/trace.go:171","msg":"trace[727989934] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"146.102013ms","start":"2026-05-13T15:39:12.884171Z","end":"2026-05-13T15:39:13.030273Z","steps":["trace[727989934] 'process raft request' (duration: 145.772986ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:45.645343Z","caller":"traceutil/trace.go:171","msg":"trace[886695341] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"118.812052ms","start":"2026-05-13T15:39:45.526505Z","end":"2026-05-13T15:39:45.645317Z","steps":["trace[886695341] 'process raft request' (duration: 118.670439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:39:46.397126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.14257ms","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-13T15:39:46.397219Z","caller":"traceutil/trace.go:171","msg":"trace[666361847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"119.287183ms","start":"2026-05-13T15:39:46.277911Z","end":"2026-05-13T15:39:46.397198Z","steps":["trace[666361847] 'range keys from in-memory index tree' (duration: 118.939587ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:51.953729Z","caller":"traceutil/trace.go:171","msg":"trace[1288102911] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"121.155814ms","start":"2026-05-13T15:39:51.832534Z","end":"2026-05-13T15:39:51.953689Z","steps":["trace[1288102911] 'process raft request' (duration: 120.732185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:39:52.264232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.004673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T15:39:52.264367Z","caller":"traceutil/trace.go:171","msg":"trace[1893381013] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"125.365588ms","start":"2026-05-13T15:39:52.138983Z","end":"2026-05-13T15:39:52.264348Z","steps":["trace[1893381013] 'read index received' (duration: 46.861µs)","trace[1893381013] 'applied index is now lower than readState.Index' (duration: 125.317277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:39:52.264613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.657084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:119762"} {"level":"info","ts":"2026-05-13T15:39:52.264437Z","caller":"traceutil/trace.go:171","msg":"trace[407119073] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"306.923649ms","start":"2026-05-13T15:39:51.957471Z","end":"2026-05-13T15:39:52.264394Z","steps":["trace[407119073] 'process raft request' (duration: 143.941115ms)","trace[407119073] 'compare' (duration: 161.746257ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:39:52.26475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.724405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-13T15:39:52.265125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T15:39:51.957448Z","time spent":"307.585473ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T15:39:52.265238Z","caller":"traceutil/trace.go:171","msg":"trace[108340964] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2161; }","duration":"126.274638ms","start":"2026-05-13T15:39:52.13894Z","end":"2026-05-13T15:39:52.265214Z","steps":["trace[108340964] 'agreement among raft nodes before linearized reading' (duration: 125.727206ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:52.26497Z","caller":"traceutil/trace.go:171","msg":"trace[886803808] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2161; }","duration":"126.044802ms","start":"2026-05-13T15:39:52.138901Z","end":"2026-05-13T15:39:52.264946Z","steps":["trace[886803808] 'agreement among raft nodes before linearized reading' (duration: 125.554561ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:52.461503Z","caller":"traceutil/trace.go:171","msg":"trace[532927982] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"192.725549ms","start":"2026-05-13T15:39:52.268754Z","end":"2026-05-13T15:39:52.46148Z","steps":["trace[532927982] 'read index received' (duration: 179.404125ms)","trace[532927982] 'applied index is now lower than readState.Index' (duration: 13.320424ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:39:52.461848Z","caller":"traceutil/trace.go:171","msg":"trace[1163807544] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"192.926313ms","start":"2026-05-13T15:39:52.268696Z","end":"2026-05-13T15:39:52.461622Z","steps":["trace[1163807544] 'process raft request' (duration: 179.498067ms)","trace[1163807544] 'compare' (duration: 13.17461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:39:52.462346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.567387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25767"} {"level":"info","ts":"2026-05-13T15:39:52.462418Z","caller":"traceutil/trace.go:171","msg":"trace[206564256] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2162; }","duration":"193.70208ms","start":"2026-05-13T15:39:52.268699Z","end":"2026-05-13T15:39:52.462401Z","steps":["trace[206564256] 'agreement among raft nodes before linearized reading' (duration: 192.943963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:39:52.462842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.992235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-13T15:39:52.462923Z","caller":"traceutil/trace.go:171","msg":"trace[640578189] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2164; }","duration":"193.097557ms","start":"2026-05-13T15:39:52.269809Z","end":"2026-05-13T15:39:52.462906Z","steps":["trace[640578189] 'agreement among raft nodes before linearized reading' (duration: 192.958514ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:39:52.463341Z","caller":"traceutil/trace.go:171","msg":"trace[999869698] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"193.775791ms","start":"2026-05-13T15:39:52.269537Z","end":"2026-05-13T15:39:52.463313Z","steps":["trace[999869698] 'process raft request' (duration: 193.096847ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:41:08.813163Z","caller":"traceutil/trace.go:171","msg":"trace[262750452] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"135.633977ms","start":"2026-05-13T15:41:08.677501Z","end":"2026-05-13T15:41:08.813135Z","steps":["trace[262750452] 'process raft request' (duration: 135.475404ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:41:08.917157Z","caller":"traceutil/trace.go:171","msg":"trace[1253439926] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2872; }","duration":"109.969758ms","start":"2026-05-13T15:41:08.807158Z","end":"2026-05-13T15:41:08.917128Z","steps":["trace[1253439926] 'read index received' (duration: 5.793683ms)","trace[1253439926] 'applied index is now lower than readState.Index' (duration: 104.174885ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:41:08.917246Z","caller":"traceutil/trace.go:171","msg":"trace[629633976] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"193.367619ms","start":"2026-05-13T15:41:08.723845Z","end":"2026-05-13T15:41:08.917213Z","steps":["trace[629633976] 'process raft request' (duration: 109.11032ms)","trace[629633976] 'compare' (duration: 84.038715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:41:08.917406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.077208ms","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-13T15:41:08.917456Z","caller":"traceutil/trace.go:171","msg":"trace[1691613500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2771; }","duration":"108.17327ms","start":"2026-05-13T15:41:08.809269Z","end":"2026-05-13T15:41:08.917442Z","steps":["trace[1691613500] 'agreement among raft nodes before linearized reading' (duration: 108.017197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:41:08.917749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.566601ms","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-13T15:41:08.917873Z","caller":"traceutil/trace.go:171","msg":"trace[281501341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2771; }","duration":"110.716313ms","start":"2026-05-13T15:41:08.807108Z","end":"2026-05-13T15:41:08.917824Z","steps":["trace[281501341] 'agreement among raft nodes before linearized reading' (duration: 110.153242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:43:34.112968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.126681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T15:43:34.113095Z","caller":"traceutil/trace.go:171","msg":"trace[184012283] transaction","detail":"{read_only:false; response_revision:3738; number_of_response:1; }","duration":"185.905094ms","start":"2026-05-13T15:43:33.92717Z","end":"2026-05-13T15:43:34.113076Z","steps":["trace[184012283] 'process raft request' (duration: 85.57883ms)","trace[184012283] 'compare' (duration: 99.846994ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:43:34.114554Z","caller":"traceutil/trace.go:171","msg":"trace[210596862] linearizableReadLoop","detail":"{readStateIndex:3879; appliedIndex:3877; }","duration":"127.484816ms","start":"2026-05-13T15:43:33.987039Z","end":"2026-05-13T15:43:34.114524Z","steps":["trace[210596862] 'read index received' (duration: 25.72788ms)","trace[210596862] 'applied index is now lower than readState.Index' (duration: 101.755566ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:43:34.114651Z","caller":"traceutil/trace.go:171","msg":"trace[1409697364] transaction","detail":"{read_only:false; response_revision:3739; number_of_response:1; }","duration":"185.308321ms","start":"2026-05-13T15:43:33.929323Z","end":"2026-05-13T15:43:34.114632Z","steps":["trace[1409697364] 'process raft request' (duration: 185.023435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:43:34.115046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.081378ms","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-13T15:43:34.115124Z","caller":"traceutil/trace.go:171","msg":"trace[2144319459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3739; }","duration":"128.240581ms","start":"2026-05-13T15:43:33.986867Z","end":"2026-05-13T15:43:34.115107Z","steps":["trace[2144319459] 'agreement among raft nodes before linearized reading' (duration: 127.779801ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.577755Z","caller":"traceutil/trace.go:171","msg":"trace[89663470] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"160.662476ms","start":"2026-05-13T15:45:04.417066Z","end":"2026-05-13T15:45:04.577728Z","steps":["trace[89663470] 'process raft request' (duration: 160.227337ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.577818Z","caller":"traceutil/trace.go:171","msg":"trace[1414789764] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"153.479357ms","start":"2026-05-13T15:45:04.424312Z","end":"2026-05-13T15:45:04.577792Z","steps":["trace[1414789764] 'process raft request' (duration: 153.215872ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.577951Z","caller":"traceutil/trace.go:171","msg":"trace[1939559517] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"153.679761ms","start":"2026-05-13T15:45:04.4242Z","end":"2026-05-13T15:45:04.57788Z","steps":["trace[1939559517] 'process raft request' (duration: 153.248042ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.578052Z","caller":"traceutil/trace.go:171","msg":"trace[706117289] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4996; }","duration":"159.136515ms","start":"2026-05-13T15:45:04.418863Z","end":"2026-05-13T15:45:04.578Z","steps":["trace[706117289] 'read index received' (duration: 12.134245ms)","trace[706117289] 'applied index is now lower than readState.Index' (duration: 146.99926ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:04.578164Z","caller":"traceutil/trace.go:171","msg":"trace[1760446825] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4820; }","duration":"151.424904ms","start":"2026-05-13T15:45:04.426676Z","end":"2026-05-13T15:45:04.578101Z","steps":["trace[1760446825] 'process raft request' (duration: 151.083977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:04.578371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.473542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-13T15:45:04.578411Z","caller":"traceutil/trace.go:171","msg":"trace[1913746762] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4820; }","duration":"159.569884ms","start":"2026-05-13T15:45:04.418831Z","end":"2026-05-13T15:45:04.578401Z","steps":["trace[1913746762] 'agreement among raft nodes before linearized reading' (duration: 159.344609ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.591054Z","caller":"traceutil/trace.go:171","msg":"trace[155022539] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"159.350339ms","start":"2026-05-13T15:45:04.431684Z","end":"2026-05-13T15:45:04.591034Z","steps":["trace[155022539] 'process raft request' (duration: 159.148465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:04.59124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.467912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-13T15:45:04.591314Z","caller":"traceutil/trace.go:171","msg":"trace[1193876618] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4821; }","duration":"159.561783ms","start":"2026-05-13T15:45:04.431726Z","end":"2026-05-13T15:45:04.591288Z","steps":["trace[1193876618] 'agreement among raft nodes before linearized reading' (duration: 159.39808ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.729424Z","caller":"traceutil/trace.go:171","msg":"trace[2087321374] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"134.886608ms","start":"2026-05-13T15:45:04.594499Z","end":"2026-05-13T15:45:04.729386Z","steps":["trace[2087321374] 'process raft request' (duration: 97.840925ms)","trace[2087321374] 'compare' (duration: 36.812579ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:04.729685Z","caller":"traceutil/trace.go:171","msg":"trace[31370883] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"134.950209ms","start":"2026-05-13T15:45:04.59472Z","end":"2026-05-13T15:45:04.72967Z","steps":["trace[31370883] 'process raft request' (duration: 134.615552ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.729768Z","caller":"traceutil/trace.go:171","msg":"trace[324117306] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"134.50234ms","start":"2026-05-13T15:45:04.595257Z","end":"2026-05-13T15:45:04.729759Z","steps":["trace[324117306] 'process raft request' (duration: 134.312776ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:04.729827Z","caller":"traceutil/trace.go:171","msg":"trace[514104679] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5004; }","duration":"105.577835ms","start":"2026-05-13T15:45:04.624242Z","end":"2026-05-13T15:45:04.72982Z","steps":["trace[514104679] 'read index received' (duration: 68.129993ms)","trace[514104679] 'applied index is now lower than readState.Index' (duration: 37.446522ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:04.729807Z","caller":"traceutil/trace.go:171","msg":"trace[1472795932] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4827; }","duration":"103.635816ms","start":"2026-05-13T15:45:04.626146Z","end":"2026-05-13T15:45:04.729782Z","steps":["trace[1472795932] 'process raft request' (duration: 103.563264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:04.729993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.948786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-05-13T15:45:04.73002Z","caller":"traceutil/trace.go:171","msg":"trace[845860889] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4827; }","duration":"107.032837ms","start":"2026-05-13T15:45:04.62298Z","end":"2026-05-13T15:45:04.730013Z","steps":["trace[845860889] 'agreement among raft nodes before linearized reading' (duration: 106.888723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:08.774967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.029349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T15:45:08.775069Z","caller":"traceutil/trace.go:171","msg":"trace[1869884024] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"185.857723ms","start":"2026-05-13T15:45:08.589194Z","end":"2026-05-13T15:45:08.775051Z","steps":["trace[1869884024] 'process raft request' (duration: 41.64635ms)","trace[1869884024] 'compare' (duration: 143.859506ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:08.954635Z","caller":"traceutil/trace.go:171","msg":"trace[1588761109] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5162; }","duration":"208.942136ms","start":"2026-05-13T15:45:08.745662Z","end":"2026-05-13T15:45:08.954604Z","steps":["trace[1588761109] 'read index received' (duration: 197.796323ms)","trace[1588761109] 'applied index is now lower than readState.Index' (duration: 11.142683ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:08.95482Z","caller":"traceutil/trace.go:171","msg":"trace[861879256] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"347.652714ms","start":"2026-05-13T15:45:08.607145Z","end":"2026-05-13T15:45:08.954797Z","steps":["trace[861879256] 'process raft request' (duration: 336.234466ms)","trace[861879256] 'compare' (duration: 10.998509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:45:08.954936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T15:45:08.60712Z","time spent":"347.737215ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T15:45:08.976911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.235502ms","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":"warn","ts":"2026-05-13T15:45:08.97692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.791641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-069ad298-8ff8-4f24-b28c-1eec3be0fda8\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-13T15:45:08.976974Z","caller":"traceutil/trace.go:171","msg":"trace[737886690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4979; }","duration":"231.350464ms","start":"2026-05-13T15:45:08.745608Z","end":"2026-05-13T15:45:08.976959Z","steps":["trace[737886690] 'agreement among raft nodes before linearized reading' (duration: 209.094549ms)","trace[737886690] 'range keys from in-memory index tree' (duration: 22.092962ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:08.977002Z","caller":"traceutil/trace.go:171","msg":"trace[1952838428] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-069ad298-8ff8-4f24-b28c-1eec3be0fda8; range_end:; response_count:1; response_revision:4979; }","duration":"228.927944ms","start":"2026-05-13T15:45:08.748054Z","end":"2026-05-13T15:45:08.976982Z","steps":["trace[1952838428] 'agreement among raft nodes before linearized reading' (duration: 228.698128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:08.977291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.650195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T15:45:08.977332Z","caller":"traceutil/trace.go:171","msg":"trace[2015463416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4979; }","duration":"169.724367ms","start":"2026-05-13T15:45:08.807599Z","end":"2026-05-13T15:45:08.977324Z","steps":["trace[2015463416] 'agreement among raft nodes before linearized reading' (duration: 169.663875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:45:08.977351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.599276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2tkg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-13T15:45:08.977378Z","caller":"traceutil/trace.go:171","msg":"trace[1019157894] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2tkg; range_end:; response_count:1; response_revision:4979; }","duration":"227.652528ms","start":"2026-05-13T15:45:08.749719Z","end":"2026-05-13T15:45:08.977371Z","steps":["trace[1019157894] 'agreement among raft nodes before linearized reading' (duration: 227.563176ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:11.129399Z","caller":"traceutil/trace.go:171","msg":"trace[1704937986] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"136.774928ms","start":"2026-05-13T15:45:10.992596Z","end":"2026-05-13T15:45:11.129371Z","steps":["trace[1704937986] 'process raft request' (duration: 108.979747ms)","trace[1704937986] 'compare' (duration: 27.689149ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:45:26.525034Z","caller":"traceutil/trace.go:171","msg":"trace[1050127031] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"105.417093ms","start":"2026-05-13T15:45:26.419596Z","end":"2026-05-13T15:45:26.525013Z","steps":["trace[1050127031] 'process raft request' (duration: 104.536284ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:27.011999Z","caller":"traceutil/trace.go:171","msg":"trace[473104950] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"183.720008ms","start":"2026-05-13T15:45:26.828249Z","end":"2026-05-13T15:45:27.011969Z","steps":["trace[473104950] 'process raft request' (duration: 183.552095ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:45:37.985014Z","caller":"traceutil/trace.go:171","msg":"trace[1021371181] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"122.664694ms","start":"2026-05-13T15:45:37.862322Z","end":"2026-05-13T15:45:37.984987Z","steps":["trace[1021371181] 'process raft request' (duration: 122.504739ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:46:50.966035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-05-13T15:46:51.048019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"80.705666ms","hash":4084087013,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-13T15:46:51.048259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084087013,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-05-13T15:51:46.06559Z","caller":"traceutil/trace.go:171","msg":"trace[116577485] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"125.707166ms","start":"2026-05-13T15:51:45.939854Z","end":"2026-05-13T15:51:46.065561Z","steps":["trace[116577485] 'process raft request' (duration: 125.545583ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:51:46.254875Z","caller":"traceutil/trace.go:171","msg":"trace[1560456271] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"176.952989ms","start":"2026-05-13T15:51:46.077896Z","end":"2026-05-13T15:51:46.254849Z","steps":["trace[1560456271] 'process raft request' (duration: 176.798055ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:51:46.255948Z","caller":"traceutil/trace.go:171","msg":"trace[1680495600] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"177.955991ms","start":"2026-05-13T15:51:46.077961Z","end":"2026-05-13T15:51:46.255916Z","steps":["trace[1680495600] 'process raft request' (duration: 177.804078ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:51:50.972878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6114} {"level":"info","ts":"2026-05-13T15:51:51.064819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6114,"took":"89.879569ms","hash":2910525300,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":23560192,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-13T15:51:51.064894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2910525300,"revision":6114,"compact-revision":3001} {"level":"info","ts":"2026-05-13T15:51:57.34484Z","caller":"traceutil/trace.go:171","msg":"trace[1660949832] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"208.564857ms","start":"2026-05-13T15:51:57.136253Z","end":"2026-05-13T15:51:57.344818Z","steps":["trace[1660949832] 'process raft request' (duration: 208.431974ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:51:57.377047Z","caller":"traceutil/trace.go:171","msg":"trace[760261516] linearizableReadLoop","detail":"{readStateIndex:8605; appliedIndex:8603; }","duration":"125.292467ms","start":"2026-05-13T15:51:57.251732Z","end":"2026-05-13T15:51:57.377025Z","steps":["trace[760261516] 'read index received' (duration: 92.965064ms)","trace[760261516] 'applied index is now lower than readState.Index' (duration: 32.326383ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:51:57.377101Z","caller":"traceutil/trace.go:171","msg":"trace[757325589] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"140.212604ms","start":"2026-05-13T15:51:57.236866Z","end":"2026-05-13T15:51:57.377079Z","steps":["trace[757325589] 'process raft request' (duration: 140.047259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:51:57.377247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.496192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2tkg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-13T15:51:57.377286Z","caller":"traceutil/trace.go:171","msg":"trace[1559464360] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2tkg; range_end:; response_count:1; response_revision:8314; }","duration":"125.589704ms","start":"2026-05-13T15:51:57.251687Z","end":"2026-05-13T15:51:57.377276Z","steps":["trace[1559464360] 'agreement among raft nodes before linearized reading' (duration: 125.440191ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:51:58.23009Z","caller":"traceutil/trace.go:171","msg":"trace[1945724657] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"113.52987ms","start":"2026-05-13T15:51:58.116536Z","end":"2026-05-13T15:51:58.230066Z","steps":["trace[1945724657] 'process raft request' (duration: 113.355776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:51:59.698571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.902246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-13T15:51:59.699233Z","caller":"traceutil/trace.go:171","msg":"trace[239275297] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"317.523599ms","start":"2026-05-13T15:51:59.381677Z","end":"2026-05-13T15:51:59.699201Z","steps":["trace[239275297] 'process raft request' (duration: 316.975257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:51:59.699514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T15:51:59.381658Z","time spent":"317.760684ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T15:51:59.699333Z","caller":"traceutil/trace.go:171","msg":"trace[298472321] linearizableReadLoop","detail":"{readStateIndex:8616; appliedIndex:8613; }","duration":"310.139162ms","start":"2026-05-13T15:51:59.38915Z","end":"2026-05-13T15:51:59.699289Z","steps":["trace[298472321] 'read index received' (duration: 45.255026ms)","trace[298472321] 'applied index is now lower than readState.Index' (duration: 264.878875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:51:59.750414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.253093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2tkg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-13T15:51:59.750589Z","caller":"traceutil/trace.go:171","msg":"trace[260869324] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2tkg; range_end:; response_count:1; response_revision:8324; }","duration":"361.445187ms","start":"2026-05-13T15:51:59.389119Z","end":"2026-05-13T15:51:59.750565Z","steps":["trace[260869324] 'agreement among raft nodes before linearized reading' (duration: 310.280776ms)","trace[260869324] 'range keys from in-memory index tree' (duration: 50.892125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T15:51:59.750704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T15:51:59.389103Z","time spent":"361.583419ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-z2tkg\" "} {"level":"warn","ts":"2026-05-13T15:51:59.750737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.597474ms","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-13T15:51:59.750812Z","caller":"traceutil/trace.go:171","msg":"trace[960217070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8325; }","duration":"249.686535ms","start":"2026-05-13T15:51:59.501111Z","end":"2026-05-13T15:51:59.750798Z","steps":["trace[960217070] 'agreement among raft nodes before linearized reading' (duration: 249.43649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:51:59.750695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.529051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-05-13T15:51:59.750906Z","caller":"traceutil/trace.go:171","msg":"trace[311145718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8325; }","duration":"301.781186ms","start":"2026-05-13T15:51:59.449113Z","end":"2026-05-13T15:51:59.750894Z","steps":["trace[311145718] 'agreement among raft nodes before linearized reading' (duration: 301.415269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:51:59.75099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T15:51:59.449092Z","time spent":"301.845557ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-13T15:51:59.75058Z","caller":"traceutil/trace.go:171","msg":"trace[1578159886] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"133.521241ms","start":"2026-05-13T15:51:59.617031Z","end":"2026-05-13T15:51:59.750552Z","steps":["trace[1578159886] 'process raft request' (duration: 82.780329ms)","trace[1578159886] 'compare' (duration: 50.584829ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:52:30.647799Z","caller":"traceutil/trace.go:171","msg":"trace[1592605953] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8842; }","duration":"236.740061ms","start":"2026-05-13T15:52:30.41104Z","end":"2026-05-13T15:52:30.64778Z","steps":["trace[1592605953] 'read index received' (duration: 236.635749ms)","trace[1592605953] 'applied index is now lower than readState.Index' (duration: 103.752µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:52:30.64785Z","caller":"traceutil/trace.go:171","msg":"trace[627001086] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"269.600596ms","start":"2026-05-13T15:52:30.378233Z","end":"2026-05-13T15:52:30.647834Z","steps":["trace[627001086] 'process raft request' (duration: 269.432032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:52:30.647955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.908145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-05-13T15:52:30.647977Z","caller":"traceutil/trace.go:171","msg":"trace[2128075873] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8543; }","duration":"236.945846ms","start":"2026-05-13T15:52:30.411025Z","end":"2026-05-13T15:52:30.647971Z","steps":["trace[2128075873] 'agreement among raft nodes before linearized reading' (duration: 236.843914ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:52:30.678219Z","caller":"traceutil/trace.go:171","msg":"trace[654401419] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"166.684672ms","start":"2026-05-13T15:52:30.511515Z","end":"2026-05-13T15:52:30.678199Z","steps":["trace[654401419] 'process raft request' (duration: 166.497608ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:52:30.678385Z","caller":"traceutil/trace.go:171","msg":"trace[1100613223] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"164.999386ms","start":"2026-05-13T15:52:30.513352Z","end":"2026-05-13T15:52:30.678352Z","steps":["trace[1100613223] 'process raft request' (duration: 164.781251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:52:30.6784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.446019ms","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-13T15:52:30.678457Z","caller":"traceutil/trace.go:171","msg":"trace[143721772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8545; }","duration":"150.539401ms","start":"2026-05-13T15:52:30.527902Z","end":"2026-05-13T15:52:30.678441Z","steps":["trace[143721772] 'agreement among raft nodes before linearized reading' (duration: 150.364857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T15:52:30.678464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.029399ms","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-13T15:52:30.678537Z","caller":"traceutil/trace.go:171","msg":"trace[1364492880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8545; }","duration":"149.169421ms","start":"2026-05-13T15:52:30.529349Z","end":"2026-05-13T15:52:30.678519Z","steps":["trace[1364492880] 'agreement among raft nodes before linearized reading' (duration: 148.861855ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:52:40.765583Z","caller":"traceutil/trace.go:171","msg":"trace[1607786077] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"136.175298ms","start":"2026-05-13T15:52:40.629367Z","end":"2026-05-13T15:52:40.765542Z","steps":["trace[1607786077] 'process raft request' (duration: 135.947943ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:53:01.281186Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ef.wal"} {"level":"info","ts":"2026-05-13T15:54:48.690468Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-13T15:54:48.696155Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-13T15:54:48.69626Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-13T15:54:54.072703Z","caller":"traceutil/trace.go:171","msg":"trace[1922958254] transaction","detail":"{read_only:false; response_revision:9693; number_of_response:1; }","duration":"123.198221ms","start":"2026-05-13T15:54:53.94948Z","end":"2026-05-13T15:54:54.072678Z","steps":["trace[1922958254] 'process raft request' (duration: 72.652114ms)","trace[1922958254] 'compare' (duration: 50.446205ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T15:54:54.120912Z","caller":"traceutil/trace.go:171","msg":"trace[161353786] transaction","detail":"{read_only:false; response_revision:9694; number_of_response:1; }","duration":"160.415576ms","start":"2026-05-13T15:54:53.960449Z","end":"2026-05-13T15:54:54.120865Z","steps":["trace[161353786] 'process raft request' (duration: 160.13639ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T15:56:50.978786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8281} {"level":"info","ts":"2026-05-13T15:56:51.05571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8281,"took":"75.345178ms","hash":36530368,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":23834624,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-13T15:56:51.055776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":36530368,"revision":8281,"compact-revision":6114} {"level":"info","ts":"2026-05-13T15:59:52.62516Z","caller":"traceutil/trace.go:171","msg":"trace[1572560282] transaction","detail":"{read_only:false; response_revision:11916; number_of_response:1; }","duration":"140.67102ms","start":"2026-05-13T15:59:52.484464Z","end":"2026-05-13T15:59:52.625135Z","steps":["trace[1572560282] 'process raft request' (duration: 95.392464ms)","trace[1572560282] 'compare' (duration: 45.136623ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:01:50.989379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10504} {"level":"info","ts":"2026-05-13T16:01:51.067317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10504,"took":"76.030636ms","hash":2048645787,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":26697728,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-13T16:01:51.067379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2048645787,"revision":10504,"compact-revision":8281} {"level":"info","ts":"2026-05-13T16:06:16.621246Z","caller":"traceutil/trace.go:171","msg":"trace[1566438156] transaction","detail":"{read_only:false; response_revision:14533; number_of_response:1; }","duration":"124.01949ms","start":"2026-05-13T16:06:16.497202Z","end":"2026-05-13T16:06:16.621222Z","steps":["trace[1566438156] 'process raft request' (duration: 123.862675ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:06:17.860848Z","caller":"traceutil/trace.go:171","msg":"trace[630650746] transaction","detail":"{read_only:false; response_revision:14543; number_of_response:1; }","duration":"134.381318ms","start":"2026-05-13T16:06:17.726444Z","end":"2026-05-13T16:06:17.860825Z","steps":["trace[630650746] 'process raft request' (duration: 126.090292ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:06:50.995669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12732} {"level":"info","ts":"2026-05-13T16:06:51.075181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12732,"took":"76.991117ms","hash":3658020817,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":28663808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-13T16:06:51.075237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3658020817,"revision":12732,"compact-revision":10504} {"level":"info","ts":"2026-05-13T16:07:30.884186Z","caller":"traceutil/trace.go:171","msg":"trace[1601667046] transaction","detail":"{read_only:false; response_revision:15476; number_of_response:1; }","duration":"100.155191ms","start":"2026-05-13T16:07:30.784007Z","end":"2026-05-13T16:07:30.884163Z","steps":["trace[1601667046] 'process raft request' (duration: 99.839924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:07:54.507879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T16:07:54.50797Z","caller":"traceutil/trace.go:171","msg":"trace[665357008] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:15602; }","duration":"114.055257ms","start":"2026-05-13T16:07:54.393895Z","end":"2026-05-13T16:07:54.507951Z","steps":["trace[665357008] 'count revisions from in-memory index tree' (duration: 113.804841ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:07:54.644751Z","caller":"traceutil/trace.go:171","msg":"trace[1239895214] transaction","detail":"{read_only:false; response_revision:15603; number_of_response:1; }","duration":"132.583641ms","start":"2026-05-13T16:07:54.512142Z","end":"2026-05-13T16:07:54.644726Z","steps":["trace[1239895214] 'process raft request' (duration: 132.415837ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:11:51.002041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14939} {"level":"info","ts":"2026-05-13T16:11:51.096966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14939,"took":"91.79163ms","hash":1967533128,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-13T16:11:51.097041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1967533128,"revision":14939,"compact-revision":12732} {"level":"info","ts":"2026-05-13T16:14:06.292313Z","caller":"traceutil/trace.go:171","msg":"trace[1879876258] transaction","detail":"{read_only:false; response_revision:18368; number_of_response:1; }","duration":"150.890257ms","start":"2026-05-13T16:14:06.141393Z","end":"2026-05-13T16:14:06.292283Z","steps":["trace[1879876258] 'process raft request' (duration: 150.56339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:11.816765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121702458626694,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T16:14:12.074939Z","caller":"traceutil/trace.go:171","msg":"trace[1195187763] transaction","detail":"{read_only:false; response_revision:18417; number_of_response:1; }","duration":"776.177311ms","start":"2026-05-13T16:14:11.298735Z","end":"2026-05-13T16:14:12.074912Z","steps":["trace[1195187763] 'process raft request' (duration: 776.018008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:12.075307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:14:11.298709Z","time spent":"776.497998ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:14:12.083834Z","caller":"traceutil/trace.go:171","msg":"trace[1759570184] transaction","detail":"{read_only:false; response_revision:18418; number_of_response:1; }","duration":"461.519777ms","start":"2026-05-13T16:14:11.622301Z","end":"2026-05-13T16:14:12.083821Z","steps":["trace[1759570184] 'process raft request' (duration: 461.338653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:12.083976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:14:11.622281Z","time spent":"461.647739ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:14:12.084056Z","caller":"traceutil/trace.go:171","msg":"trace[352263647] transaction","detail":"{read_only:false; response_revision:18419; number_of_response:1; }","duration":"405.143133ms","start":"2026-05-13T16:14:11.678902Z","end":"2026-05-13T16:14:12.084045Z","steps":["trace[352263647] 'process raft request' (duration: 404.852417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:12.084159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:14:11.678882Z","time spent":"405.221224ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:14:12.083816Z","caller":"traceutil/trace.go:171","msg":"trace[2110112404] linearizableReadLoop","detail":"{readStateIndex:19048; appliedIndex:19045; }","duration":"767.842984ms","start":"2026-05-13T16:14:11.315947Z","end":"2026-05-13T16:14:12.08379Z","steps":["trace[2110112404] 'read index received' (duration: 758.910374ms)","trace[2110112404] 'applied index is now lower than readState.Index' (duration: 8.9317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:14:12.084016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.052159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T16:14:12.084304Z","caller":"traceutil/trace.go:171","msg":"trace[520082297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18419; }","duration":"768.366096ms","start":"2026-05-13T16:14:11.31591Z","end":"2026-05-13T16:14:12.084276Z","steps":["trace[520082297] 'agreement among raft nodes before linearized reading' (duration: 767.952538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:12.084385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:14:11.315894Z","time spent":"768.467368ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T16:14:12.084464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.065707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m94wl\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-05-13T16:14:12.084532Z","caller":"traceutil/trace.go:171","msg":"trace[264072056] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m94wl; range_end:; response_count:1; response_revision:18419; }","duration":"563.174229ms","start":"2026-05-13T16:14:11.521338Z","end":"2026-05-13T16:14:12.084512Z","steps":["trace[264072056] 'agreement among raft nodes before linearized reading' (duration: 562.965685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:14:12.084572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:14:11.521321Z","time spent":"563.24238ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9811,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-m94wl\" "} {"level":"info","ts":"2026-05-13T16:15:49.150627Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-13T16:15:49.155361Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-13T16:15:49.155427Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-13T16:16:51.030872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17413} {"level":"info","ts":"2026-05-13T16:16:51.130732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17413,"took":"96.389702ms","hash":2384406135,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-13T16:16:51.130784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2384406135,"revision":17413,"compact-revision":14939} {"level":"info","ts":"2026-05-13T16:20:40.193435Z","caller":"traceutil/trace.go:171","msg":"trace[1433157694] transaction","detail":"{read_only:false; response_revision:21276; number_of_response:1; }","duration":"133.856014ms","start":"2026-05-13T16:20:40.059528Z","end":"2026-05-13T16:20:40.193384Z","steps":["trace[1433157694] 'process raft request' (duration: 114.115146ms)","trace[1433157694] 'compare' (duration: 19.475693ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:21:32.167328Z","caller":"traceutil/trace.go:171","msg":"trace[1839214970] transaction","detail":"{read_only:false; response_revision:21539; number_of_response:1; }","duration":"121.092438ms","start":"2026-05-13T16:21:32.046203Z","end":"2026-05-13T16:21:32.167295Z","steps":["trace[1839214970] 'process raft request' (duration: 120.876483ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:21:32.358796Z","caller":"traceutil/trace.go:171","msg":"trace[1376858020] transaction","detail":"{read_only:false; response_revision:21540; number_of_response:1; }","duration":"112.624638ms","start":"2026-05-13T16:21:32.246146Z","end":"2026-05-13T16:21:32.35877Z","steps":["trace[1376858020] 'process raft request' (duration: 74.269318ms)","trace[1376858020] 'compare' (duration: 38.166936ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:21:32.358999Z","caller":"traceutil/trace.go:171","msg":"trace[608837798] transaction","detail":"{read_only:false; response_revision:21541; number_of_response:1; }","duration":"111.512145ms","start":"2026-05-13T16:21:32.247432Z","end":"2026-05-13T16:21:32.358944Z","steps":["trace[608837798] 'process raft request' (duration: 111.29798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:21:32.632535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.247314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:21:32.632737Z","caller":"traceutil/trace.go:171","msg":"trace[1302168484] transaction","detail":"{read_only:false; response_revision:21542; number_of_response:1; }","duration":"243.539524ms","start":"2026-05-13T16:21:32.389138Z","end":"2026-05-13T16:21:32.632677Z","steps":["trace[1302168484] 'process raft request' (duration: 138.044225ms)","trace[1302168484] 'compare' (duration: 105.064309ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:21:32.893414Z","caller":"traceutil/trace.go:171","msg":"trace[1213433487] transaction","detail":"{read_only:false; response_revision:21543; number_of_response:1; }","duration":"256.969148ms","start":"2026-05-13T16:21:32.636418Z","end":"2026-05-13T16:21:32.893387Z","steps":["trace[1213433487] 'process raft request' (duration: 256.790895ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:21:32.89906Z","caller":"traceutil/trace.go:171","msg":"trace[570590270] linearizableReadLoop","detail":"{readStateIndex:22279; appliedIndex:22277; }","duration":"219.105488ms","start":"2026-05-13T16:21:32.67993Z","end":"2026-05-13T16:21:32.899036Z","steps":["trace[570590270] 'read index received' (duration: 213.291856ms)","trace[570590270] 'applied index is now lower than readState.Index' (duration: 5.812462ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:21:32.899188Z","caller":"traceutil/trace.go:171","msg":"trace[715614832] transaction","detail":"{read_only:false; response_revision:21544; number_of_response:1; }","duration":"262.650838ms","start":"2026-05-13T16:21:32.636505Z","end":"2026-05-13T16:21:32.899155Z","steps":["trace[715614832] 'process raft request' (duration: 262.387782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:21:32.899391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.301182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:21:32.899474Z","caller":"traceutil/trace.go:171","msg":"trace[114959698] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:21544; }","duration":"219.594449ms","start":"2026-05-13T16:21:32.67986Z","end":"2026-05-13T16:21:32.899454Z","steps":["trace[114959698] 'agreement among raft nodes before linearized reading' (duration: 219.307503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:21:33.187943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.3167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:21:33.188051Z","caller":"traceutil/trace.go:171","msg":"trace[231766768] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:21544; }","duration":"173.488264ms","start":"2026-05-13T16:21:33.014539Z","end":"2026-05-13T16:21:33.188027Z","steps":["trace[231766768] 'count revisions from in-memory index tree' (duration: 172.895132ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:21:51.035913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19755} {"level":"info","ts":"2026-05-13T16:21:51.143361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19755,"took":"103.947086ms","hash":1623517554,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":28200960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-13T16:21:51.143429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623517554,"revision":19755,"compact-revision":17413} {"level":"info","ts":"2026-05-13T16:26:51.042602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21642} {"level":"info","ts":"2026-05-13T16:26:51.146382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21642,"took":"100.787299ms","hash":81436881,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":42151936,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-13T16:26:51.146449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":81436881,"revision":21642,"compact-revision":19755} {"level":"info","ts":"2026-05-13T16:28:58.244849Z","caller":"traceutil/trace.go:171","msg":"trace[1824825950] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"202.405745ms","start":"2026-05-13T16:28:58.042421Z","end":"2026-05-13T16:28:58.244827Z","steps":["trace[1824825950] 'process raft request' (duration: 202.255451ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:28:58.409935Z","caller":"traceutil/trace.go:171","msg":"trace[1576106761] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"176.762484ms","start":"2026-05-13T16:28:58.233146Z","end":"2026-05-13T16:28:58.409908Z","steps":["trace[1576106761] 'process raft request' (duration: 176.678432ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:28:58.409962Z","caller":"traceutil/trace.go:171","msg":"trace[2111531290] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"191.544196ms","start":"2026-05-13T16:28:58.218378Z","end":"2026-05-13T16:28:58.409922Z","steps":["trace[2111531290] 'process raft request' (duration: 158.051148ms)","trace[2111531290] 'compare' (duration: 33.168261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:28:59.000755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.20151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T16:28:59.000828Z","caller":"traceutil/trace.go:171","msg":"trace[611227329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26108; }","duration":"299.322352ms","start":"2026-05-13T16:28:58.701488Z","end":"2026-05-13T16:28:59.00081Z","steps":["trace[611227329] 'range keys from in-memory index tree' (duration: 299.020506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.000958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.034013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-13T16:28:59.001018Z","caller":"traceutil/trace.go:171","msg":"trace[1348103277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26108; }","duration":"296.130855ms","start":"2026-05-13T16:28:58.704873Z","end":"2026-05-13T16:28:59.001004Z","steps":["trace[1348103277] 'range keys from in-memory index tree' (duration: 295.87342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.001165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.219191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:28:59.001188Z","caller":"traceutil/trace.go:171","msg":"trace[1228669353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26108; }","duration":"193.269883ms","start":"2026-05-13T16:28:58.807913Z","end":"2026-05-13T16:28:59.001183Z","steps":["trace[1228669353] 'range keys from in-memory index tree' (duration: 193.15976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.001497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.702615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:28:59.001558Z","caller":"traceutil/trace.go:171","msg":"trace[1506484746] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:26108; }","duration":"375.776416ms","start":"2026-05-13T16:28:58.625771Z","end":"2026-05-13T16:28:59.001548Z","steps":["trace[1506484746] 'count revisions from in-memory index tree' (duration: 375.612714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.001577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:58.625712Z","time spent":"375.858159ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-05-13T16:28:59.19361Z","caller":"traceutil/trace.go:171","msg":"trace[1695954083] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"187.221295ms","start":"2026-05-13T16:28:59.006359Z","end":"2026-05-13T16:28:59.19358Z","steps":["trace[1695954083] 'process raft request' (duration: 187.042891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.44021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.282862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:28:59.440728Z","caller":"traceutil/trace.go:171","msg":"trace[146184032] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"432.943755ms","start":"2026-05-13T16:28:59.007759Z","end":"2026-05-13T16:28:59.440703Z","steps":["trace[146184032] 'process raft request' (duration: 275.108851ms)","trace[146184032] 'compare' (duration: 156.865794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:28:59.440959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.007744Z","time spent":"433.069177ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:28:59.755646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.32283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-13T16:28:59.756052Z","caller":"traceutil/trace.go:171","msg":"trace[1177008977] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26973; }","duration":"630.164499ms","start":"2026-05-13T16:28:59.125866Z","end":"2026-05-13T16:28:59.756031Z","steps":["trace[1177008977] 'read index received' (duration: 67.471235ms)","trace[1177008977] 'applied index is now lower than readState.Index' (duration: 562.691154ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:28:59.756214Z","caller":"traceutil/trace.go:171","msg":"trace[1264506576] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"508.089792ms","start":"2026-05-13T16:28:59.248059Z","end":"2026-05-13T16:28:59.756149Z","steps":["trace[1264506576] 'process raft request' (duration: 507.754524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.756383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.248035Z","time spent":"508.268275ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:28:59.944125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.221531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-13T16:28:59.944245Z","caller":"traceutil/trace.go:171","msg":"trace[1007767059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26111; }","duration":"818.366535ms","start":"2026-05-13T16:28:59.125838Z","end":"2026-05-13T16:28:59.944205Z","steps":["trace[1007767059] 'agreement among raft nodes before linearized reading' (duration: 630.253252ms)","trace[1007767059] 'range keys from in-memory index tree' (duration: 187.819337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:28:59.944292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.125826Z","time spent":"818.456026ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-13T16:28:59.944336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.094713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:28:59.944927Z","caller":"traceutil/trace.go:171","msg":"trace[1846300794] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"695.649762ms","start":"2026-05-13T16:28:59.249249Z","end":"2026-05-13T16:28:59.944899Z","steps":["trace[1846300794] 'process raft request' (duration: 506.881425ms)","trace[1846300794] 'compare' (duration: 187.906469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:28:59.945026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.249237Z","time spent":"695.738255ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:28:59.945063Z","caller":"traceutil/trace.go:171","msg":"trace[448966146] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26977; }","duration":"188.94246ms","start":"2026-05-13T16:28:59.756102Z","end":"2026-05-13T16:28:59.945044Z","steps":["trace[448966146] 'read index received' (duration: 15.432705ms)","trace[448966146] 'applied index is now lower than readState.Index' (duration: 173.508005ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:28:59.94508Z","caller":"traceutil/trace.go:171","msg":"trace[1286512122] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"264.613668ms","start":"2026-05-13T16:28:59.680433Z","end":"2026-05-13T16:28:59.945047Z","steps":["trace[1286512122] 'process raft request' (duration: 264.116138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.945233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.83848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-13T16:28:59.94526Z","caller":"traceutil/trace.go:171","msg":"trace[180242196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26116; }","duration":"404.908121ms","start":"2026-05-13T16:28:59.540346Z","end":"2026-05-13T16:28:59.945254Z","steps":["trace[180242196] 'agreement among raft nodes before linearized reading' (duration: 404.787889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.945277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.540328Z","time spent":"404.945153ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-13T16:28:59.945299Z","caller":"traceutil/trace.go:171","msg":"trace[746614967] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"265.065599ms","start":"2026-05-13T16:28:59.680214Z","end":"2026-05-13T16:28:59.94528Z","steps":["trace[746614967] 'process raft request' (duration: 264.240692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.945378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.244443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-13T16:28:59.945446Z","caller":"traceutil/trace.go:171","msg":"trace[2117423000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26116; }","duration":"819.302904ms","start":"2026-05-13T16:28:59.12611Z","end":"2026-05-13T16:28:59.945413Z","steps":["trace[2117423000] 'agreement among raft nodes before linearized reading' (duration: 819.174351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:28:59.945507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:28:59.126104Z","time spent":"819.386145ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-13T16:29:00.176825Z","caller":"traceutil/trace.go:171","msg":"trace[436172783] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"226.833521ms","start":"2026-05-13T16:28:59.949965Z","end":"2026-05-13T16:29:00.176798Z","steps":["trace[436172783] 'process raft request' (duration: 203.035208ms)","trace[436172783] 'compare' (duration: 23.61645ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:00.176849Z","caller":"traceutil/trace.go:171","msg":"trace[351933714] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"224.268647ms","start":"2026-05-13T16:28:59.952559Z","end":"2026-05-13T16:29:00.176828Z","steps":["trace[351933714] 'process raft request' (duration: 224.233486ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:00.177109Z","caller":"traceutil/trace.go:171","msg":"trace[1325357662] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"226.649697ms","start":"2026-05-13T16:28:59.950424Z","end":"2026-05-13T16:29:00.177074Z","steps":["trace[1325357662] 'process raft request' (duration: 226.32432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:05.622479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.179175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T16:29:05.622537Z","caller":"traceutil/trace.go:171","msg":"trace[1278021107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26159; }","duration":"161.283507ms","start":"2026-05-13T16:29:05.461241Z","end":"2026-05-13T16:29:05.622524Z","steps":["trace[1278021107] 'range keys from in-memory index tree' (duration: 161.013892ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:05.796616Z","caller":"traceutil/trace.go:171","msg":"trace[903763633] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"247.603961ms","start":"2026-05-13T16:29:05.548964Z","end":"2026-05-13T16:29:05.796568Z","steps":["trace[903763633] 'process raft request' (duration: 247.294904ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.073806Z","caller":"traceutil/trace.go:171","msg":"trace[569006155] linearizableReadLoop","detail":"{readStateIndex:27030; appliedIndex:27027; }","duration":"266.236974ms","start":"2026-05-13T16:29:05.807552Z","end":"2026-05-13T16:29:06.073789Z","steps":["trace[569006155] 'read index received' (duration: 266.03225ms)","trace[569006155] 'applied index is now lower than readState.Index' (duration: 203.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:06.073809Z","caller":"traceutil/trace.go:171","msg":"trace[563486059] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"446.590854ms","start":"2026-05-13T16:29:05.627185Z","end":"2026-05-13T16:29:06.073776Z","steps":["trace[563486059] 'process raft request' (duration: 446.386569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.073905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.339396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:06.073903Z","caller":"traceutil/trace.go:171","msg":"trace[978299608] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"407.094679ms","start":"2026-05-13T16:29:05.666776Z","end":"2026-05-13T16:29:06.073871Z","steps":["trace[978299608] 'process raft request' (duration: 406.932465ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.073944Z","caller":"traceutil/trace.go:171","msg":"trace[1309794506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26163; }","duration":"266.390347ms","start":"2026-05-13T16:29:05.807547Z","end":"2026-05-13T16:29:06.073937Z","steps":["trace[1309794506] 'agreement among raft nodes before linearized reading' (duration: 266.323066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.073962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.62716Z","time spent":"446.726556ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:06.073962Z","caller":"traceutil/trace.go:171","msg":"trace[871370480] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"296.406741ms","start":"2026-05-13T16:29:05.777519Z","end":"2026-05-13T16:29:06.073926Z","steps":["trace[871370480] 'process raft request' (duration: 296.220457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.074038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.666751Z","time spent":"407.210601ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.404527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.231344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:06.404834Z","caller":"traceutil/trace.go:171","msg":"trace[2025295372] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"437.392108ms","start":"2026-05-13T16:29:05.967421Z","end":"2026-05-13T16:29:06.404813Z","steps":["trace[2025295372] 'process raft request' (duration: 437.248625ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.404885Z","caller":"traceutil/trace.go:171","msg":"trace[636432382] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"436.341456ms","start":"2026-05-13T16:29:05.968505Z","end":"2026-05-13T16:29:06.404847Z","steps":["trace[636432382] 'process raft request' (duration: 436.232194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.404947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.967393Z","time spent":"437.50043ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.404967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.710898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:06.404965Z","caller":"traceutil/trace.go:171","msg":"trace[1233875929] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"437.454779ms","start":"2026-05-13T16:29:05.967497Z","end":"2026-05-13T16:29:06.404952Z","steps":["trace[1233875929] 'process raft request' (duration: 437.203974ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.404951Z","caller":"traceutil/trace.go:171","msg":"trace[357681465] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"437.546281ms","start":"2026-05-13T16:29:05.967377Z","end":"2026-05-13T16:29:06.404923Z","steps":["trace[357681465] 'process raft request' (duration: 437.234955ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.405001Z","caller":"traceutil/trace.go:171","msg":"trace[1630597300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26169; }","duration":"324.76674ms","start":"2026-05-13T16:29:06.080223Z","end":"2026-05-13T16:29:06.40499Z","steps":["trace[1630597300] 'agreement among raft nodes before linearized reading' (duration: 324.713639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.405031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.08021Z","time spent":"324.814491ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T16:29:06.405064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.967479Z","time spent":"437.529541ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:06.404907Z","caller":"traceutil/trace.go:171","msg":"trace[1042452616] linearizableReadLoop","detail":"{readStateIndex:27035; appliedIndex:27030; }","duration":"324.639297ms","start":"2026-05-13T16:29:06.080252Z","end":"2026-05-13T16:29:06.404891Z","steps":["trace[1042452616] 'read index received' (duration: 173.906883ms)","trace[1042452616] 'applied index is now lower than readState.Index' (duration: 150.731714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:06.405068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.967353Z","time spent":"437.662663ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.405054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.968483Z","time spent":"436.479129ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.405233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.974252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-13T16:29:06.405252Z","caller":"traceutil/trace.go:171","msg":"trace[1410909151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26169; }","duration":"207.013023ms","start":"2026-05-13T16:29:06.198233Z","end":"2026-05-13T16:29:06.405246Z","steps":["trace[1410909151] 'agreement among raft nodes before linearized reading' (duration: 206.940572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.405309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.046634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-13T16:29:06.405287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.434343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-13T16:29:06.405343Z","caller":"traceutil/trace.go:171","msg":"trace[1819620364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26169; }","duration":"208.121276ms","start":"2026-05-13T16:29:06.197209Z","end":"2026-05-13T16:29:06.405331Z","steps":["trace[1819620364] 'agreement among raft nodes before linearized reading' (duration: 208.008504ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.405347Z","caller":"traceutil/trace.go:171","msg":"trace[670009319] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26169; }","duration":"280.522865ms","start":"2026-05-13T16:29:06.124813Z","end":"2026-05-13T16:29:06.405336Z","steps":["trace[670009319] 'agreement among raft nodes before linearized reading' (duration: 280.344491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.405329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.298497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:06.405393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.146518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-13T16:29:06.405409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.102056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-13T16:29:06.405419Z","caller":"traceutil/trace.go:171","msg":"trace[263925908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26169; }","duration":"209.20685ms","start":"2026-05-13T16:29:06.196204Z","end":"2026-05-13T16:29:06.405411Z","steps":["trace[263925908] 'agreement among raft nodes before linearized reading' (duration: 209.115818ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.405427Z","caller":"traceutil/trace.go:171","msg":"trace[669818875] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26169; }","duration":"192.146418ms","start":"2026-05-13T16:29:06.213276Z","end":"2026-05-13T16:29:06.405423Z","steps":["trace[669818875] 'agreement among raft nodes before linearized reading' (duration: 192.022145ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.405417Z","caller":"traceutil/trace.go:171","msg":"trace[865959108] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26169; }","duration":"170.46528ms","start":"2026-05-13T16:29:06.234926Z","end":"2026-05-13T16:29:06.405391Z","steps":["trace[865959108] 'agreement among raft nodes before linearized reading' (duration: 170.299937ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.40547Z","caller":"traceutil/trace.go:171","msg":"trace[1575709721] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"438.067612ms","start":"2026-05-13T16:29:05.967363Z","end":"2026-05-13T16:29:06.405431Z","steps":["trace[1575709721] 'process raft request' (duration: 286.865368ms)","trace[1575709721] 'compare' (duration: 149.979069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:06.405629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:05.967343Z","time spent":"438.203514ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.718723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.634465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:06.718813Z","caller":"traceutil/trace.go:171","msg":"trace[1222130539] linearizableReadLoop","detail":"{readStateIndex:27037; appliedIndex:27036; }","duration":"308.778832ms","start":"2026-05-13T16:29:06.41002Z","end":"2026-05-13T16:29:06.718799Z","steps":["trace[1222130539] 'read index received' (duration: 145.003063ms)","trace[1222130539] 'applied index is now lower than readState.Index' (duration: 163.774819ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:06.718872Z","caller":"traceutil/trace.go:171","msg":"trace[1781080453] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"309.387794ms","start":"2026-05-13T16:29:06.409473Z","end":"2026-05-13T16:29:06.718861Z","steps":["trace[1781080453] 'process raft request' (duration: 145.532244ms)","trace[1781080453] 'compare' (duration: 163.230097ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:06.71892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.409457Z","time spent":"309.435506ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.719073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.041407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-13T16:29:06.719137Z","caller":"traceutil/trace.go:171","msg":"trace[1653777042] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26170; }","duration":"309.13419ms","start":"2026-05-13T16:29:06.409986Z","end":"2026-05-13T16:29:06.71912Z","steps":["trace[1653777042] 'agreement among raft nodes before linearized reading' (duration: 308.925205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.719176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.409971Z","time spent":"309.196691ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18439,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-13T16:29:06.878824Z","caller":"traceutil/trace.go:171","msg":"trace[380379644] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"467.980654ms","start":"2026-05-13T16:29:06.410813Z","end":"2026-05-13T16:29:06.878794Z","steps":["trace[380379644] 'process raft request' (duration: 439.810459ms)","trace[380379644] 'compare' (duration: 27.865039ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:06.878907Z","caller":"traceutil/trace.go:171","msg":"trace[1835592610] linearizableReadLoop","detail":"{readStateIndex:27040; appliedIndex:27037; }","duration":"160.03793ms","start":"2026-05-13T16:29:06.718834Z","end":"2026-05-13T16:29:06.878872Z","steps":["trace[1835592610] 'read index received' (duration: 131.719342ms)","trace[1835592610] 'applied index is now lower than readState.Index' (duration: 28.317328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:06.87898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.4108Z","time spent":"468.094217ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:06.878987Z","caller":"traceutil/trace.go:171","msg":"trace[1185008877] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"468.090227ms","start":"2026-05-13T16:29:06.410877Z","end":"2026-05-13T16:29:06.878967Z","steps":["trace[1185008877] 'process raft request' (duration: 467.857742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.879096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.410857Z","time spent":"468.177048ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:06.87913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.577684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-13T16:29:06.879167Z","caller":"traceutil/trace.go:171","msg":"trace[497878727] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26173; }","duration":"350.664786ms","start":"2026-05-13T16:29:06.52849Z","end":"2026-05-13T16:29:06.879154Z","steps":["trace[497878727] 'agreement among raft nodes before linearized reading' (duration: 350.510053ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:06.879147Z","caller":"traceutil/trace.go:171","msg":"trace[233266660] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"447.660085ms","start":"2026-05-13T16:29:06.431446Z","end":"2026-05-13T16:29:06.879106Z","steps":["trace[233266660] 'process raft request' (duration: 447.349659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:06.879201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.52847Z","time spent":"350.722897ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-13T16:29:06.879322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.431422Z","time spent":"447.811668ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:07.195858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.535344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:07.196162Z","caller":"traceutil/trace.go:171","msg":"trace[81911990] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"312.724754ms","start":"2026-05-13T16:29:06.883404Z","end":"2026-05-13T16:29:07.196129Z","steps":["trace[81911990] 'process raft request' (duration: 139.836433ms)","trace[81911990] 'compare' (duration: 172.109995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:07.196365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:06.883383Z","time spent":"312.881288ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:07.396199Z","caller":"traceutil/trace.go:171","msg":"trace[1837399168] linearizableReadLoop","detail":"{readStateIndex:27042; appliedIndex:27041; }","duration":"116.994152ms","start":"2026-05-13T16:29:07.279179Z","end":"2026-05-13T16:29:07.396174Z","steps":["trace[1837399168] 'read index received' (duration: 88.740765ms)","trace[1837399168] 'applied index is now lower than readState.Index' (duration: 28.252287ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:07.396597Z","caller":"traceutil/trace.go:171","msg":"trace[1566947919] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"166.62498ms","start":"2026-05-13T16:29:07.229956Z","end":"2026-05-13T16:29:07.396581Z","steps":["trace[1566947919] 'process raft request' (duration: 138.035037ms)","trace[1566947919] 'compare' (duration: 28.075523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:07.396645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.450891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:07.396707Z","caller":"traceutil/trace.go:171","msg":"trace[604182034] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26175; }","duration":"117.547473ms","start":"2026-05-13T16:29:07.279145Z","end":"2026-05-13T16:29:07.396693Z","steps":["trace[604182034] 'agreement among raft nodes before linearized reading' (duration: 117.43259ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:08.689788Z","caller":"traceutil/trace.go:171","msg":"trace[943176138] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"279.81267ms","start":"2026-05-13T16:29:08.409947Z","end":"2026-05-13T16:29:08.68976Z","steps":["trace[943176138] 'process raft request' (duration: 279.629716ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:08.970984Z","caller":"traceutil/trace.go:171","msg":"trace[1246263234] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"560.716972ms","start":"2026-05-13T16:29:08.410219Z","end":"2026-05-13T16:29:08.970936Z","steps":["trace[1246263234] 'process raft request' (duration: 560.493878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:08.97114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.4102Z","time spent":"560.861926ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:08.971251Z","caller":"traceutil/trace.go:171","msg":"trace[585520180] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"560.338585ms","start":"2026-05-13T16:29:08.41088Z","end":"2026-05-13T16:29:08.971218Z","steps":["trace[585520180] 'process raft request' (duration: 560.013529ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:08.971259Z","caller":"traceutil/trace.go:171","msg":"trace[1103493191] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"560.190182ms","start":"2026-05-13T16:29:08.411041Z","end":"2026-05-13T16:29:08.971231Z","steps":["trace[1103493191] 'process raft request' (duration: 560.031118ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:08.971307Z","caller":"traceutil/trace.go:171","msg":"trace[1470663064] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27045; }","duration":"247.055178ms","start":"2026-05-13T16:29:08.724233Z","end":"2026-05-13T16:29:08.971289Z","steps":["trace[1470663064] 'read index received' (duration: 246.471936ms)","trace[1470663064] 'applied index is now lower than readState.Index' (duration: 582.662µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:08.971404Z","caller":"traceutil/trace.go:171","msg":"trace[993173187] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"558.337053ms","start":"2026-05-13T16:29:08.413056Z","end":"2026-05-13T16:29:08.971393Z","steps":["trace[993173187] 'process raft request' (duration: 558.111648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:08.971393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.41102Z","time spent":"560.299994ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:08.971401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.410853Z","time spent":"560.463157ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:08.9715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.413045Z","time spent":"558.382954ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:08.971536Z","caller":"traceutil/trace.go:171","msg":"trace[492586134] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"540.66056ms","start":"2026-05-13T16:29:08.430865Z","end":"2026-05-13T16:29:08.971526Z","steps":["trace[492586134] 'process raft request' (duration: 540.366914ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:08.971558Z","caller":"traceutil/trace.go:171","msg":"trace[1249412194] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"557.711409ms","start":"2026-05-13T16:29:08.413832Z","end":"2026-05-13T16:29:08.971543Z","steps":["trace[1249412194] 'process raft request' (duration: 557.366062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:08.971592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.430837Z","time spent":"540.72441ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:08.971593Z","caller":"traceutil/trace.go:171","msg":"trace[1062920571] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"483.169245ms","start":"2026-05-13T16:29:08.488402Z","end":"2026-05-13T16:29:08.971571Z","steps":["trace[1062920571] 'process raft request' (duration: 482.855768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:08.971635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.413823Z","time spent":"557.774261ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:08.971676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:08.488379Z","time spent":"483.255877ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:08.971735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.507908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-13T16:29:08.971773Z","caller":"traceutil/trace.go:171","msg":"trace[1965411385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26185; }","duration":"247.582099ms","start":"2026-05-13T16:29:08.724182Z","end":"2026-05-13T16:29:08.971764Z","steps":["trace[1965411385] 'agreement among raft nodes before linearized reading' (duration: 247.422055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.025848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.414568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-13T16:29:09.025962Z","caller":"traceutil/trace.go:171","msg":"trace[1506871254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26186; }","duration":"142.574421ms","start":"2026-05-13T16:29:08.883373Z","end":"2026-05-13T16:29:09.025947Z","steps":["trace[1506871254] 'agreement among raft nodes before linearized reading' (duration: 142.350587ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:09.026233Z","caller":"traceutil/trace.go:171","msg":"trace[1780494033] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"140.776744ms","start":"2026-05-13T16:29:08.885443Z","end":"2026-05-13T16:29:09.02622Z","steps":["trace[1780494033] 'process raft request' (duration: 140.173831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.026463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.704564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-13T16:29:09.026534Z","caller":"traceutil/trace.go:171","msg":"trace[423320157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26186; }","duration":"142.798055ms","start":"2026-05-13T16:29:08.883724Z","end":"2026-05-13T16:29:09.026522Z","steps":["trace[423320157] 'agreement among raft nodes before linearized reading' (duration: 142.673323ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:09.321343Z","caller":"traceutil/trace.go:171","msg":"trace[1494189932] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"272.683079ms","start":"2026-05-13T16:29:09.048635Z","end":"2026-05-13T16:29:09.321318Z","steps":["trace[1494189932] 'process raft request' (duration: 272.499365ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:09.680542Z","caller":"traceutil/trace.go:171","msg":"trace[1228016730] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"631.812004ms","start":"2026-05-13T16:29:09.048705Z","end":"2026-05-13T16:29:09.680517Z","steps":["trace[1228016730] 'process raft request' (duration: 623.449797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.680678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:09.048662Z","time spent":"631.933687ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:09.919685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.52715ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-13T16:29:09.91979Z","caller":"traceutil/trace.go:171","msg":"trace[1563312876] linearizableReadLoop","detail":"{readStateIndex:27056; appliedIndex:27054; }","duration":"734.273608ms","start":"2026-05-13T16:29:09.185499Z","end":"2026-05-13T16:29:09.919772Z","steps":["trace[1563312876] 'read index received' (duration: 135.713285ms)","trace[1563312876] 'applied index is now lower than readState.Index' (duration: 598.558713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:09.920062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.545775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:09.920125Z","caller":"traceutil/trace.go:171","msg":"trace[340242260] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26189; }","duration":"734.620746ms","start":"2026-05-13T16:29:09.185487Z","end":"2026-05-13T16:29:09.920108Z","steps":["trace[340242260] 'agreement among raft nodes before linearized reading' (duration: 734.490223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.92011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.169729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:09.920163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:09.185397Z","time spent":"734.755018ms","remote":"127.0.0.1:49702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:09.920202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.983398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:09.92021Z","caller":"traceutil/trace.go:171","msg":"trace[954278229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"112.301652ms","start":"2026-05-13T16:29:09.807884Z","end":"2026-05-13T16:29:09.920186Z","steps":["trace[954278229] 'agreement among raft nodes before linearized reading' (duration: 112.143518ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:09.920252Z","caller":"traceutil/trace.go:171","msg":"trace[2103679782] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26189; }","duration":"409.05952ms","start":"2026-05-13T16:29:09.51117Z","end":"2026-05-13T16:29:09.92023Z","steps":["trace[2103679782] 'agreement among raft nodes before linearized reading' (duration: 409.003728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.920287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:09.511133Z","time spent":"409.147002ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:09.92014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.651807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-13T16:29:09.920366Z","caller":"traceutil/trace.go:171","msg":"trace[240986449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26189; }","duration":"511.919392ms","start":"2026-05-13T16:29:09.408435Z","end":"2026-05-13T16:29:09.920355Z","steps":["trace[240986449] 'agreement among raft nodes before linearized reading' (duration: 511.593195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:09.920403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:09.408417Z","time spent":"511.975174ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-13T16:29:10.132333Z","caller":"traceutil/trace.go:171","msg":"trace[1116269793] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"208.156398ms","start":"2026-05-13T16:29:09.924113Z","end":"2026-05-13T16:29:10.132269Z","steps":["trace[1116269793] 'process raft request' (duration: 204.401757ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:10.298378Z","caller":"traceutil/trace.go:171","msg":"trace[1725121738] linearizableReadLoop","detail":"{readStateIndex:27060; appliedIndex:27059; }","duration":"141.969599ms","start":"2026-05-13T16:29:10.156379Z","end":"2026-05-13T16:29:10.298349Z","steps":["trace[1725121738] 'read index received' (duration: 125.621964ms)","trace[1725121738] 'applied index is now lower than readState.Index' (duration: 16.345695ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:10.298413Z","caller":"traceutil/trace.go:171","msg":"trace[1847463143] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"156.152548ms","start":"2026-05-13T16:29:10.142226Z","end":"2026-05-13T16:29:10.298379Z","steps":["trace[1847463143] 'process raft request' (duration: 139.781293ms)","trace[1847463143] 'compare' (duration: 16.139261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:10.298629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.239084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-13T16:29:10.29866Z","caller":"traceutil/trace.go:171","msg":"trace[625492108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26192; }","duration":"142.318376ms","start":"2026-05-13T16:29:10.156332Z","end":"2026-05-13T16:29:10.298651Z","steps":["trace[625492108] 'agreement among raft nodes before linearized reading' (duration: 142.151272ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:10.454526Z","caller":"traceutil/trace.go:171","msg":"trace[1693663698] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"153.990012ms","start":"2026-05-13T16:29:10.300512Z","end":"2026-05-13T16:29:10.454503Z","steps":["trace[1693663698] 'process raft request' (duration: 153.840028ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:10.466287Z","caller":"traceutil/trace.go:171","msg":"trace[419430056] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"163.455852ms","start":"2026-05-13T16:29:10.302811Z","end":"2026-05-13T16:29:10.466267Z","steps":["trace[419430056] 'process raft request' (duration: 163.34481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:10.87919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.131307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-13T16:29:10.879281Z","caller":"traceutil/trace.go:171","msg":"trace[806106797] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26194; }","duration":"317.279491ms","start":"2026-05-13T16:29:10.561981Z","end":"2026-05-13T16:29:10.87926Z","steps":["trace[806106797] 'count revisions from in-memory index tree' (duration: 316.973064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:10.879331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:10.561961Z","time spent":"317.357342ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":74,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-13T16:29:10.879847Z","caller":"traceutil/trace.go:171","msg":"trace[923705430] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"181.231347ms","start":"2026-05-13T16:29:10.698128Z","end":"2026-05-13T16:29:10.87936Z","steps":["trace[923705430] 'process raft request' (duration: 86.926206ms)","trace[923705430] 'compare' (duration: 94.042855ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:11.083198Z","caller":"traceutil/trace.go:171","msg":"trace[1040484665] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"106.266244ms","start":"2026-05-13T16:29:10.976904Z","end":"2026-05-13T16:29:11.08317Z","steps":["trace[1040484665] 'process raft request' (duration: 105.658491ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:11.389294Z","caller":"traceutil/trace.go:171","msg":"trace[137521452] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"412.148655ms","start":"2026-05-13T16:29:10.977121Z","end":"2026-05-13T16:29:11.389269Z","steps":["trace[137521452] 'process raft request' (duration: 403.89553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.389441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:10.977111Z","time spent":"412.247637ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:11.389825Z","caller":"traceutil/trace.go:171","msg":"trace[325982097] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"411.606723ms","start":"2026-05-13T16:29:10.978208Z","end":"2026-05-13T16:29:11.389814Z","steps":["trace[325982097] 'process raft request' (duration: 411.526612ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:11.389841Z","caller":"traceutil/trace.go:171","msg":"trace[1401628157] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"412.38357ms","start":"2026-05-13T16:29:10.977428Z","end":"2026-05-13T16:29:11.389811Z","steps":["trace[1401628157] 'process raft request' (duration: 411.766207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.389882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:10.978189Z","time spent":"411.667235ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:11.38999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:10.977421Z","time spent":"412.492892ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:11.390503Z","caller":"traceutil/trace.go:171","msg":"trace[1178312101] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"411.229756ms","start":"2026-05-13T16:29:10.979252Z","end":"2026-05-13T16:29:11.390481Z","steps":["trace[1178312101] 'process raft request' (duration: 410.536241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.390589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:10.979226Z","time spent":"411.322627ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:11.392002Z","caller":"traceutil/trace.go:171","msg":"trace[1048934731] linearizableReadLoop","detail":"{readStateIndex:27069; appliedIndex:27063; }","duration":"341.452772ms","start":"2026-05-13T16:29:11.050349Z","end":"2026-05-13T16:29:11.391802Z","steps":["trace[1048934731] 'read index received' (duration: 32.358024ms)","trace[1048934731] 'applied index is now lower than readState.Index' (duration: 309.093688ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:11.39249Z","caller":"traceutil/trace.go:171","msg":"trace[1317961627] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"347.569281ms","start":"2026-05-13T16:29:11.044905Z","end":"2026-05-13T16:29:11.392475Z","steps":["trace[1317961627] 'process raft request' (duration: 345.798185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.393146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.771221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-13T16:29:11.393196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:11.044877Z","time spent":"348.059302ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:11.393223Z","caller":"traceutil/trace.go:171","msg":"trace[1010154043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26201; }","duration":"342.901024ms","start":"2026-05-13T16:29:11.050304Z","end":"2026-05-13T16:29:11.393205Z","steps":["trace[1010154043] 'agreement among raft nodes before linearized reading' (duration: 342.536686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.393746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:11.050288Z","time spent":"343.323682ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-13T16:29:11.550513Z","caller":"traceutil/trace.go:171","msg":"trace[1167682686] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"153.563473ms","start":"2026-05-13T16:29:11.396887Z","end":"2026-05-13T16:29:11.55045Z","steps":["trace[1167682686] 'process raft request' (duration: 137.968844ms)","trace[1167682686] 'compare' (duration: 15.410876ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:11.903567Z","caller":"traceutil/trace.go:171","msg":"trace[847306205] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27069; }","duration":"398.480956ms","start":"2026-05-13T16:29:11.505045Z","end":"2026-05-13T16:29:11.903526Z","steps":["trace[847306205] 'read index received' (duration: 29.833769ms)","trace[847306205] 'applied index is now lower than readState.Index' (duration: 368.645967ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:11.903606Z","caller":"traceutil/trace.go:171","msg":"trace[1818940063] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"504.909925ms","start":"2026-05-13T16:29:11.398648Z","end":"2026-05-13T16:29:11.903558Z","steps":["trace[1818940063] 'process raft request' (duration: 487.963917ms)","trace[1818940063] 'compare' (duration: 16.519519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:11.90379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:11.39863Z","time spent":"505.053177ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:11.903929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.204592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-13T16:29:11.904039Z","caller":"traceutil/trace.go:171","msg":"trace[382711091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26203; }","duration":"220.373535ms","start":"2026-05-13T16:29:11.683636Z","end":"2026-05-13T16:29:11.904009Z","steps":["trace[382711091] 'agreement among raft nodes before linearized reading' (duration: 220.1141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.904328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.269153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:11.904463Z","caller":"traceutil/trace.go:171","msg":"trace[1457165544] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26203; }","duration":"399.452897ms","start":"2026-05-13T16:29:11.504988Z","end":"2026-05-13T16:29:11.90444Z","steps":["trace[1457165544] 'agreement among raft nodes before linearized reading' (duration: 399.244143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:11.904513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:11.504963Z","time spent":"399.538068ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:12.223151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.02031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:12.223281Z","caller":"traceutil/trace.go:171","msg":"trace[607127111] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"315.417682ms","start":"2026-05-13T16:29:11.907833Z","end":"2026-05-13T16:29:12.223251Z","steps":["trace[607127111] 'process raft request' (duration: 128.207138ms)","trace[607127111] 'compare' (duration: 186.651543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:12.223429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:11.907808Z","time spent":"315.540484ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:12.496781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.388472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:12.497051Z","caller":"traceutil/trace.go:171","msg":"trace[572574023] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"193.444935ms","start":"2026-05-13T16:29:12.303588Z","end":"2026-05-13T16:29:12.497033Z","steps":["trace[572574023] 'process raft request' (duration: 193.297822ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:12.497179Z","caller":"traceutil/trace.go:171","msg":"trace[1913285369] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"269.558523ms","start":"2026-05-13T16:29:12.227427Z","end":"2026-05-13T16:29:12.496985Z","steps":["trace[1913285369] 'process raft request' (duration: 113.872035ms)","trace[1913285369] 'compare' (duration: 155.151916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:12.837793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.212452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:12.837892Z","caller":"traceutil/trace.go:171","msg":"trace[1297216916] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26207; }","duration":"240.318485ms","start":"2026-05-13T16:29:12.59755Z","end":"2026-05-13T16:29:12.837868Z","steps":["trace[1297216916] 'range keys from in-memory index tree' (duration: 240.066239ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:14.491955Z","caller":"traceutil/trace.go:171","msg":"trace[1313579271] linearizableReadLoop","detail":"{readStateIndex:27088; appliedIndex:27087; }","duration":"246.086468ms","start":"2026-05-13T16:29:14.245843Z","end":"2026-05-13T16:29:14.491929Z","steps":["trace[1313579271] 'read index received' (duration: 245.855873ms)","trace[1313579271] 'applied index is now lower than readState.Index' (duration: 229.674µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:14.492005Z","caller":"traceutil/trace.go:171","msg":"trace[1642621770] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"260.692436ms","start":"2026-05-13T16:29:14.231279Z","end":"2026-05-13T16:29:14.491971Z","steps":["trace[1642621770] 'process raft request' (duration: 260.484862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:14.492125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.254521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-13T16:29:14.492271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.032246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:14.492324Z","caller":"traceutil/trace.go:171","msg":"trace[657205583] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26219; }","duration":"246.356073ms","start":"2026-05-13T16:29:14.245802Z","end":"2026-05-13T16:29:14.492158Z","steps":["trace[657205583] 'agreement among raft nodes before linearized reading' (duration: 246.254681ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:14.492379Z","caller":"traceutil/trace.go:171","msg":"trace[1263079798] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26219; }","duration":"192.21883ms","start":"2026-05-13T16:29:14.300132Z","end":"2026-05-13T16:29:14.492351Z","steps":["trace[1263079798] 'agreement among raft nodes before linearized reading' (duration: 192.006996ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:14.689943Z","caller":"traceutil/trace.go:171","msg":"trace[1377042756] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"188.44045ms","start":"2026-05-13T16:29:14.501478Z","end":"2026-05-13T16:29:14.689919Z","steps":["trace[1377042756] 'process raft request' (duration: 188.281647ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:14.69071Z","caller":"traceutil/trace.go:171","msg":"trace[1787061492] linearizableReadLoop","detail":"{readStateIndex:27090; appliedIndex:27088; }","duration":"177.51621ms","start":"2026-05-13T16:29:14.513163Z","end":"2026-05-13T16:29:14.690679Z","steps":["trace[1787061492] 'read index received' (duration: 176.63016ms)","trace[1787061492] 'applied index is now lower than readState.Index' (duration: 884.62µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:14.691003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.783626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T16:29:14.691061Z","caller":"traceutil/trace.go:171","msg":"trace[22428806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26221; }","duration":"177.914928ms","start":"2026-05-13T16:29:14.513127Z","end":"2026-05-13T16:29:14.691041Z","steps":["trace[22428806] 'agreement among raft nodes before linearized reading' (duration: 177.666583ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:14.6911Z","caller":"traceutil/trace.go:171","msg":"trace[1747778927] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"186.02859ms","start":"2026-05-13T16:29:14.505039Z","end":"2026-05-13T16:29:14.691067Z","steps":["trace[1747778927] 'process raft request' (duration: 185.471518ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:24.490292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a24.wal"} {"level":"warn","ts":"2026-05-13T16:29:26.209923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.520654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-13T16:29:26.21001Z","caller":"traceutil/trace.go:171","msg":"trace[555922579] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647; range_end:; response_count:1; response_revision:26363; }","duration":"119.650357ms","start":"2026-05-13T16:29:26.090342Z","end":"2026-05-13T16:29:26.209992Z","steps":["trace[555922579] 'range keys from in-memory index tree' (duration: 119.352011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:26.210048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.120512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-13T16:29:26.210068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.084994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:26.210129Z","caller":"traceutil/trace.go:171","msg":"trace[740275842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26363; }","duration":"404.171657ms","start":"2026-05-13T16:29:25.805938Z","end":"2026-05-13T16:29:26.21011Z","steps":["trace[740275842] 'range keys from in-memory index tree' (duration: 404.015583ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:26.210131Z","caller":"traceutil/trace.go:171","msg":"trace[386940418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26363; }","duration":"240.213174ms","start":"2026-05-13T16:29:25.969895Z","end":"2026-05-13T16:29:26.210108Z","steps":["trace[386940418] 'range keys from in-memory index tree' (duration: 239.901597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:26.210127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.144405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:26.210166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:25.805921Z","time spent":"404.237328ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T16:29:26.210219Z","caller":"traceutil/trace.go:171","msg":"trace[10183670] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26363; }","duration":"439.304839ms","start":"2026-05-13T16:29:25.770893Z","end":"2026-05-13T16:29:26.210198Z","steps":["trace[10183670] 'count revisions from in-memory index tree' (duration: 439.051203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:26.21027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:25.770878Z","time spent":"439.38079ms","remote":"127.0.0.1:49774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-05-13T16:29:26.377093Z","caller":"traceutil/trace.go:171","msg":"trace[1262827854] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"162.189075ms","start":"2026-05-13T16:29:26.214882Z","end":"2026-05-13T16:29:26.377071Z","steps":["trace[1262827854] 'process raft request' (duration: 162.062442ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:26.93017Z","caller":"traceutil/trace.go:171","msg":"trace[1800485156] linearizableReadLoop","detail":"{readStateIndex:27237; appliedIndex:27236; }","duration":"389.379664ms","start":"2026-05-13T16:29:26.540776Z","end":"2026-05-13T16:29:26.930156Z","steps":["trace[1800485156] 'read index received' (duration: 389.248221ms)","trace[1800485156] 'applied index is now lower than readState.Index' (duration: 130.673µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:26.930221Z","caller":"traceutil/trace.go:171","msg":"trace[544031409] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"712.633682ms","start":"2026-05-13T16:29:26.217555Z","end":"2026-05-13T16:29:26.930188Z","steps":["trace[544031409] 'process raft request' (duration: 712.439358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:26.930314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.547788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-13T16:29:26.930318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.217545Z","time spent":"712.706264ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:26.930347Z","caller":"traceutil/trace.go:171","msg":"trace[934757200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26365; }","duration":"389.619849ms","start":"2026-05-13T16:29:26.540719Z","end":"2026-05-13T16:29:26.930339Z","steps":["trace[934757200] 'agreement among raft nodes before linearized reading' (duration: 389.516987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:26.930373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.540692Z","time spent":"389.67561ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-13T16:29:27.430745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121702458648585,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T16:29:27.460738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.925896ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:27.460827Z","caller":"traceutil/trace.go:171","msg":"trace[1074221014] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26365; }","duration":"240.024018ms","start":"2026-05-13T16:29:27.22079Z","end":"2026-05-13T16:29:27.460814Z","steps":["trace[1074221014] 'range keys from in-memory index tree' (duration: 239.912826ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:27.470124Z","caller":"traceutil/trace.go:171","msg":"trace[1223608853] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"785.46879ms","start":"2026-05-13T16:29:26.684631Z","end":"2026-05-13T16:29:27.4701Z","steps":["trace[1223608853] 'process raft request' (duration: 784.944778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.470328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.684606Z","time spent":"785.597613ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:27.470488Z","caller":"traceutil/trace.go:171","msg":"trace[2138193692] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"541.748833ms","start":"2026-05-13T16:29:26.928717Z","end":"2026-05-13T16:29:27.470466Z","steps":["trace[2138193692] 'process raft request' (duration: 541.60229ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:27.470529Z","caller":"traceutil/trace.go:171","msg":"trace[1181931829] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"731.101191ms","start":"2026-05-13T16:29:26.739392Z","end":"2026-05-13T16:29:27.470493Z","steps":["trace[1181931829] 'process raft request' (duration: 730.644322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.47067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.9287Z","time spent":"541.884946ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:27.470676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.739369Z","time spent":"731.230664ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:27.504535Z","caller":"traceutil/trace.go:171","msg":"trace[1570306439] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"570.059961ms","start":"2026-05-13T16:29:26.934444Z","end":"2026-05-13T16:29:27.504504Z","steps":["trace[1570306439] 'process raft request' (duration: 569.863397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.504713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.934419Z","time spent":"570.197384ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:27.504874Z","caller":"traceutil/trace.go:171","msg":"trace[1913771859] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"360.348182ms","start":"2026-05-13T16:29:27.144505Z","end":"2026-05-13T16:29:27.504853Z","steps":["trace[1913771859] 'process raft request' (duration: 359.930263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.504999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.144466Z","time spent":"360.479324ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:27.505254Z","caller":"traceutil/trace.go:171","msg":"trace[285765698] linearizableReadLoop","detail":"{readStateIndex:27242; appliedIndex:27237; }","duration":"574.996114ms","start":"2026-05-13T16:29:26.930221Z","end":"2026-05-13T16:29:27.505217Z","steps":["trace[285765698] 'read index received' (duration: 539.630077ms)","trace[285765698] 'applied index is now lower than readState.Index' (duration: 35.364017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:27.505505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.512604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:27.505558Z","caller":"traceutil/trace.go:171","msg":"trace[1647245949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26371; }","duration":"699.615827ms","start":"2026-05-13T16:29:26.805926Z","end":"2026-05-13T16:29:27.505541Z","steps":["trace[1647245949] 'agreement among raft nodes before linearized reading' (duration: 699.470444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.505605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.805907Z","time spent":"699.686328ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T16:29:27.505664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.752975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-13T16:29:27.505851Z","caller":"traceutil/trace.go:171","msg":"trace[810430420] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26371; }","duration":"634.022311ms","start":"2026-05-13T16:29:26.871795Z","end":"2026-05-13T16:29:27.505817Z","steps":["trace[810430420] 'agreement among raft nodes before linearized reading' (duration: 633.466859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.505944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.871777Z","time spent":"634.153083ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-05-13T16:29:27.505975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.956938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-05-13T16:29:27.506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.783981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-13T16:29:27.506049Z","caller":"traceutil/trace.go:171","msg":"trace[444341678] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647; range_end:; response_count:1; response_revision:26371; }","duration":"865.0663ms","start":"2026-05-13T16:29:26.640962Z","end":"2026-05-13T16:29:27.506028Z","steps":["trace[444341678] 'agreement among raft nodes before linearized reading' (duration: 864.852506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.506104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.64094Z","time spent":"865.150012ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647\" "} {"level":"warn","ts":"2026-05-13T16:29:27.506267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.074892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:27.506344Z","caller":"traceutil/trace.go:171","msg":"trace[1092367512] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26371; }","duration":"634.194585ms","start":"2026-05-13T16:29:26.872126Z","end":"2026-05-13T16:29:27.506321Z","steps":["trace[1092367512] 'agreement among raft nodes before linearized reading' (duration: 634.036541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.506427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.872106Z","time spent":"634.309097ms","remote":"127.0.0.1:33702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:27.506531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.358575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-13T16:29:27.506542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.342425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-13T16:29:27.506626Z","caller":"traceutil/trace.go:171","msg":"trace[1860336334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26371; }","duration":"756.465767ms","start":"2026-05-13T16:29:26.750145Z","end":"2026-05-13T16:29:27.506611Z","steps":["trace[1860336334] 'agreement among raft nodes before linearized reading' (duration: 756.181981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.506667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.750118Z","time spent":"756.536539ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-13T16:29:27.506055Z","caller":"traceutil/trace.go:171","msg":"trace[1760184537] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26371; }","duration":"888.872593ms","start":"2026-05-13T16:29:26.617168Z","end":"2026-05-13T16:29:27.506041Z","steps":["trace[1760184537] 'agreement among raft nodes before linearized reading' (duration: 888.731051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.506751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.617149Z","time spent":"889.576779ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-13T16:29:27.506621Z","caller":"traceutil/trace.go:171","msg":"trace[484707317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26371; }","duration":"756.516498ms","start":"2026-05-13T16:29:26.750085Z","end":"2026-05-13T16:29:27.506601Z","steps":["trace[484707317] 'agreement among raft nodes before linearized reading' (duration: 756.140959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:27.506883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:26.750073Z","time spent":"756.797114ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T16:29:28.005429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121702458648593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T16:29:28.134223Z","caller":"traceutil/trace.go:171","msg":"trace[961490973] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"659.614433ms","start":"2026-05-13T16:29:27.474582Z","end":"2026-05-13T16:29:28.134196Z","steps":["trace[961490973] 'process raft request' (duration: 659.48358ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:28.134271Z","caller":"traceutil/trace.go:171","msg":"trace[1460362349] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"659.645852ms","start":"2026-05-13T16:29:27.474585Z","end":"2026-05-13T16:29:28.134231Z","steps":["trace[1460362349] 'process raft request' (duration: 659.268984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.134398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.474557Z","time spent":"659.745475ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:28.134471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.474569Z","time spent":"659.787896ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:28.134594Z","caller":"traceutil/trace.go:171","msg":"trace[1043588058] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"659.874288ms","start":"2026-05-13T16:29:27.474665Z","end":"2026-05-13T16:29:28.134539Z","steps":["trace[1043588058] 'process raft request' (duration: 659.48768ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:28.134637Z","caller":"traceutil/trace.go:171","msg":"trace[1817349158] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"658.536679ms","start":"2026-05-13T16:29:27.47607Z","end":"2026-05-13T16:29:28.134607Z","steps":["trace[1817349158] 'process raft request' (duration: 658.379206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.134762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.476061Z","time spent":"658.656671ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:28.134805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.474655Z","time spent":"660.038551ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:28.180444Z","caller":"traceutil/trace.go:171","msg":"trace[1436115170] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"666.765133ms","start":"2026-05-13T16:29:27.513642Z","end":"2026-05-13T16:29:28.180407Z","steps":["trace[1436115170] 'process raft request' (duration: 666.417466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.180665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.513626Z","time spent":"666.925716ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:28.18073Z","caller":"traceutil/trace.go:171","msg":"trace[1196778702] linearizableReadLoop","detail":"{readStateIndex:27249; appliedIndex:27243; }","duration":"675.349444ms","start":"2026-05-13T16:29:27.505351Z","end":"2026-05-13T16:29:28.180701Z","steps":["trace[1196778702] 'read index received' (duration: 628.430143ms)","trace[1196778702] 'applied index is now lower than readState.Index' (duration: 46.917951ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:28.180865Z","caller":"traceutil/trace.go:171","msg":"trace[1272433701] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"665.778581ms","start":"2026-05-13T16:29:27.515075Z","end":"2026-05-13T16:29:28.180853Z","steps":["trace[1272433701] 'process raft request' (duration: 665.23046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.180973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.515057Z","time spent":"665.833882ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:28.180967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.997863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:28.181045Z","caller":"traceutil/trace.go:171","msg":"trace[1204782105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26377; }","duration":"672.106506ms","start":"2026-05-13T16:29:27.508919Z","end":"2026-05-13T16:29:28.181026Z","steps":["trace[1204782105] 'agreement among raft nodes before linearized reading' (duration: 671.981723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.181086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.508899Z","time spent":"672.179917ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T16:29:28.181137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.317673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:28.1812Z","caller":"traceutil/trace.go:171","msg":"trace[712523668] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26377; }","duration":"541.390945ms","start":"2026-05-13T16:29:27.639774Z","end":"2026-05-13T16:29:28.181165Z","steps":["trace[712523668] 'agreement among raft nodes before linearized reading' (duration: 541.319323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.181232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.639758Z","time spent":"541.465776ms","remote":"127.0.0.1:57226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:28.181366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.291796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:28.181402Z","caller":"traceutil/trace.go:171","msg":"trace[1402973872] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26377; }","duration":"554.361947ms","start":"2026-05-13T16:29:27.627029Z","end":"2026-05-13T16:29:28.181391Z","steps":["trace[1402973872] 'agreement among raft nodes before linearized reading' (duration: 554.298996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.181431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.627011Z","time spent":"554.412639ms","remote":"127.0.0.1:32814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:28.181394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.572005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:28.181474Z","caller":"traceutil/trace.go:171","msg":"trace[1768827444] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26377; }","duration":"414.678329ms","start":"2026-05-13T16:29:27.766784Z","end":"2026-05-13T16:29:28.181463Z","steps":["trace[1768827444] 'agreement among raft nodes before linearized reading' (duration: 414.577526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.181505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.76677Z","time spent":"414.72267ms","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:28.181593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.214537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-069ad298-8ff8-4f24-b28c-1eec3be0fda8\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-05-13T16:29:28.18169Z","caller":"traceutil/trace.go:171","msg":"trace[1723477440] range","detail":"{range_begin:/registry/persistentvolumes/pvc-069ad298-8ff8-4f24-b28c-1eec3be0fda8; range_end:; response_count:1; response_revision:26377; }","duration":"671.34821ms","start":"2026-05-13T16:29:27.510322Z","end":"2026-05-13T16:29:28.18167Z","steps":["trace[1723477440] 'agreement among raft nodes before linearized reading' (duration: 671.076764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.181733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.510306Z","time spent":"671.417131ms","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1299,"request content":"key:\"/registry/persistentvolumes/pvc-069ad298-8ff8-4f24-b28c-1eec3be0fda8\" "} {"level":"warn","ts":"2026-05-13T16:29:28.181783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.165812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:28.181814Z","caller":"traceutil/trace.go:171","msg":"trace[248375304] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26377; }","duration":"971.239074ms","start":"2026-05-13T16:29:27.210565Z","end":"2026-05-13T16:29:28.181804Z","steps":["trace[248375304] 'agreement among raft nodes before linearized reading' (duration: 971.184403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.18182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.260748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-13T16:29:28.182134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.939237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-13T16:29:28.182171Z","caller":"traceutil/trace.go:171","msg":"trace[1490491439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26377; }","duration":"555.659196ms","start":"2026-05-13T16:29:27.626494Z","end":"2026-05-13T16:29:28.182153Z","steps":["trace[1490491439] 'agreement among raft nodes before linearized reading' (duration: 555.230117ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:28.182189Z","caller":"traceutil/trace.go:171","msg":"trace[531309451] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26377; }","duration":"149.680782ms","start":"2026-05-13T16:29:28.032491Z","end":"2026-05-13T16:29:28.182172Z","steps":["trace[531309451] 'agreement among raft nodes before linearized reading' (duration: 148.847173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:28.18222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.626469Z","time spent":"555.739328ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-13T16:29:28.181837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:27.210527Z","time spent":"971.303675ms","remote":"127.0.0.1:50846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:28.999606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.830253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:28.999708Z","caller":"traceutil/trace.go:171","msg":"trace[1296313741] linearizableReadLoop","detail":"{readStateIndex:27250; appliedIndex:27249; }","duration":"814.072455ms","start":"2026-05-13T16:29:28.185619Z","end":"2026-05-13T16:29:28.999691Z","steps":["trace[1296313741] 'read index received' (duration: 256.022397ms)","trace[1296313741] 'applied index is now lower than readState.Index' (duration: 558.048918ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:28.999779Z","caller":"traceutil/trace.go:171","msg":"trace[1245519437] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"814.248038ms","start":"2026-05-13T16:29:28.185503Z","end":"2026-05-13T16:29:28.999751Z","steps":["trace[1245519437] 'process raft request' (duration: 256.19552ms)","trace[1245519437] 'compare' (duration: 542.619201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:28.999922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.185484Z","time spent":"814.38022ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:28.999973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.33084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-13T16:29:29.000065Z","caller":"traceutil/trace.go:171","msg":"trace[772554433] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26378; }","duration":"814.473333ms","start":"2026-05-13T16:29:28.18557Z","end":"2026-05-13T16:29:29.000044Z","steps":["trace[772554433] 'agreement among raft nodes before linearized reading' (duration: 814.181007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.000123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.185549Z","time spent":"814.560794ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-13T16:29:29.197784Z","caller":"traceutil/trace.go:171","msg":"trace[1421593429] linearizableReadLoop","detail":"{readStateIndex:27251; appliedIndex:27250; }","duration":"198.030431ms","start":"2026-05-13T16:29:28.999737Z","end":"2026-05-13T16:29:29.197768Z","steps":["trace[1421593429] 'read index received' (duration: 197.862768ms)","trace[1421593429] 'applied index is now lower than readState.Index' (duration: 167.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:29.197882Z","caller":"traceutil/trace.go:171","msg":"trace[747171161] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"201.803272ms","start":"2026-05-13T16:29:28.996043Z","end":"2026-05-13T16:29:29.197846Z","steps":["trace[747171161] 'process raft request' (duration: 201.603908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.197934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.877242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-13T16:29:29.197952Z","caller":"traceutil/trace.go:171","msg":"trace[1981455801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26379; }","duration":"816.959274ms","start":"2026-05-13T16:29:28.380987Z","end":"2026-05-13T16:29:29.197947Z","steps":["trace[1981455801] 'agreement among raft nodes before linearized reading' (duration: 816.863392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.197973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.380962Z","time spent":"817.004716ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-13T16:29:29.198013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.007171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-13T16:29:29.198055Z","caller":"traceutil/trace.go:171","msg":"trace[1907519880] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26379; }","duration":"296.074402ms","start":"2026-05-13T16:29:28.901967Z","end":"2026-05-13T16:29:29.198042Z","steps":["trace[1907519880] 'agreement among raft nodes before linearized reading' (duration: 295.96866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.198075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.916906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:29.197895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.394631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:29.198163Z","caller":"traceutil/trace.go:171","msg":"trace[1163682804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26379; }","duration":"623.061709ms","start":"2026-05-13T16:29:28.575081Z","end":"2026-05-13T16:29:29.198143Z","steps":["trace[1163682804] 'agreement among raft nodes before linearized reading' (duration: 622.923906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.198115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.841718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18416"} {"level":"warn","ts":"2026-05-13T16:29:29.198064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009059412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:29.198215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.575058Z","time spent":"623.146341ms","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-05-13T16:29:29.198247Z","caller":"traceutil/trace.go:171","msg":"trace[2072091592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26379; }","duration":"1.009255916s","start":"2026-05-13T16:29:28.18898Z","end":"2026-05-13T16:29:29.198236Z","steps":["trace[2072091592] 'agreement among raft nodes before linearized reading' (duration: 1.009066972s)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:29.198232Z","caller":"traceutil/trace.go:171","msg":"trace[1774293689] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26379; }","duration":"196.96613ms","start":"2026-05-13T16:29:29.001245Z","end":"2026-05-13T16:29:29.198211Z","steps":["trace[1774293689] 'agreement among raft nodes before linearized reading' (duration: 196.832757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.198293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.18897Z","time spent":"1.009309407s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T16:29:29.198165Z","caller":"traceutil/trace.go:171","msg":"trace[1824553964] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26379; }","duration":"502.749268ms","start":"2026-05-13T16:29:28.6954Z","end":"2026-05-13T16:29:29.19815Z","steps":["trace[1824553964] 'agreement among raft nodes before linearized reading' (duration: 502.403741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.198404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.247059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:29.198426Z","caller":"traceutil/trace.go:171","msg":"trace[41739874] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26379; }","duration":"172.267609ms","start":"2026-05-13T16:29:29.02615Z","end":"2026-05-13T16:29:29.198417Z","steps":["trace[41739874] 'agreement among raft nodes before linearized reading' (duration: 172.238178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.198435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.695369Z","time spent":"503.037895ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:29.198853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.664081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-13T16:29:29.19934Z","caller":"traceutil/trace.go:171","msg":"trace[972078379] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647; range_end:; response_count:1; response_revision:26379; }","duration":"685.181862ms","start":"2026-05-13T16:29:28.514141Z","end":"2026-05-13T16:29:29.199323Z","steps":["trace[972078379] 'agreement among raft nodes before linearized reading' (duration: 684.587429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:29.199403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:28.514121Z","time spent":"685.268744ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c9068b-40d7-4f8f-b6e0-99468514b647\" "} {"level":"info","ts":"2026-05-13T16:29:31.741827Z","caller":"traceutil/trace.go:171","msg":"trace[1122767614] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"260.764628ms","start":"2026-05-13T16:29:31.481035Z","end":"2026-05-13T16:29:31.741799Z","steps":["trace[1122767614] 'process raft request' (duration: 260.060383ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:31.759101Z","caller":"traceutil/trace.go:171","msg":"trace[1942841716] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"242.010612ms","start":"2026-05-13T16:29:31.51707Z","end":"2026-05-13T16:29:31.759081Z","steps":["trace[1942841716] 'process raft request' (duration: 241.94022ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:31.759249Z","caller":"traceutil/trace.go:171","msg":"trace[832329500] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"275.862847ms","start":"2026-05-13T16:29:31.483379Z","end":"2026-05-13T16:29:31.759242Z","steps":["trace[832329500] 'process raft request' (duration: 275.465299ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:31.759303Z","caller":"traceutil/trace.go:171","msg":"trace[743894747] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"270.789409ms","start":"2026-05-13T16:29:31.488508Z","end":"2026-05-13T16:29:31.759297Z","steps":["trace[743894747] 'process raft request' (duration: 270.470893ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:31.759376Z","caller":"traceutil/trace.go:171","msg":"trace[1022792478] linearizableReadLoop","detail":"{readStateIndex:27274; appliedIndex:27269; }","duration":"240.379108ms","start":"2026-05-13T16:29:31.518977Z","end":"2026-05-13T16:29:31.759356Z","steps":["trace[1022792478] 'read index received' (duration: 222.160492ms)","trace[1022792478] 'applied index is now lower than readState.Index' (duration: 18.217746ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:31.759466Z","caller":"traceutil/trace.go:171","msg":"trace[1852302174] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"242.110124ms","start":"2026-05-13T16:29:31.517348Z","end":"2026-05-13T16:29:31.759458Z","steps":["trace[1852302174] 'process raft request' (duration: 241.702885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:31.759596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.610362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-13T16:29:31.759628Z","caller":"traceutil/trace.go:171","msg":"trace[1791791442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26401; }","duration":"240.699904ms","start":"2026-05-13T16:29:31.518919Z","end":"2026-05-13T16:29:31.759619Z","steps":["trace[1791791442] 'agreement among raft nodes before linearized reading' (duration: 240.566571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:31.759783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.1194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:31.75982Z","caller":"traceutil/trace.go:171","msg":"trace[543725208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26401; }","duration":"167.198482ms","start":"2026-05-13T16:29:31.592608Z","end":"2026-05-13T16:29:31.759807Z","steps":["trace[543725208] 'agreement among raft nodes before linearized reading' (duration: 167.131991ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:36.314089Z","caller":"traceutil/trace.go:171","msg":"trace[1755736206] transaction","detail":"{read_only:false; response_revision:26459; number_of_response:1; }","duration":"151.815387ms","start":"2026-05-13T16:29:36.162256Z","end":"2026-05-13T16:29:36.314072Z","steps":["trace[1755736206] 'process raft request' (duration: 151.702995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:36.608996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.04519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:36.609204Z","caller":"traceutil/trace.go:171","msg":"trace[733040880] linearizableReadLoop","detail":"{readStateIndex:27337; appliedIndex:27333; }","duration":"399.133231ms","start":"2026-05-13T16:29:36.210055Z","end":"2026-05-13T16:29:36.609188Z","steps":["trace[733040880] 'read index received' (duration: 103.910036ms)","trace[733040880] 'applied index is now lower than readState.Index' (duration: 295.222295ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:36.609297Z","caller":"traceutil/trace.go:171","msg":"trace[2122017449] transaction","detail":"{read_only:false; response_revision:26460; number_of_response:1; }","duration":"446.00363ms","start":"2026-05-13T16:29:36.163278Z","end":"2026-05-13T16:29:36.609282Z","steps":["trace[2122017449] 'process raft request' (duration: 259.602302ms)","trace[2122017449] 'compare' (duration: 185.945528ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:36.609312Z","caller":"traceutil/trace.go:171","msg":"trace[121010241] transaction","detail":"{read_only:false; response_revision:26462; number_of_response:1; }","duration":"444.810045ms","start":"2026-05-13T16:29:36.164487Z","end":"2026-05-13T16:29:36.609297Z","steps":["trace[121010241] 'process raft request' (duration: 444.657352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:36.609369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:36.164476Z","time spent":"444.861856ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:36.609523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.500666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-13T16:29:36.609556Z","caller":"traceutil/trace.go:171","msg":"trace[1840051044] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"446.03259ms","start":"2026-05-13T16:29:36.163513Z","end":"2026-05-13T16:29:36.609545Z","steps":["trace[1840051044] 'process raft request' (duration: 445.571071ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:36.609578Z","caller":"traceutil/trace.go:171","msg":"trace[991065193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26462; }","duration":"398.569868ms","start":"2026-05-13T16:29:36.210993Z","end":"2026-05-13T16:29:36.609563Z","steps":["trace[991065193] 'agreement among raft nodes before linearized reading' (duration: 398.319102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:36.60961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:36.210917Z","time spent":"398.6862ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-13T16:29:36.609616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:36.163484Z","time spent":"446.098932ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:36.609368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:36.163241Z","time spent":"446.084212ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:36.609728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.675072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-13T16:29:36.609752Z","caller":"traceutil/trace.go:171","msg":"trace[1604329930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26462; }","duration":"399.733643ms","start":"2026-05-13T16:29:36.210012Z","end":"2026-05-13T16:29:36.609745Z","steps":["trace[1604329930] 'agreement among raft nodes before linearized reading' (duration: 399.673262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:36.609769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:36.209993Z","time spent":"399.771904ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-13T16:29:36.820475Z","caller":"traceutil/trace.go:171","msg":"trace[1309782156] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"205.02849ms","start":"2026-05-13T16:29:36.615422Z","end":"2026-05-13T16:29:36.820451Z","steps":["trace[1309782156] 'process raft request' (duration: 202.61621ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:36.820595Z","caller":"traceutil/trace.go:171","msg":"trace[746661950] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"203.784283ms","start":"2026-05-13T16:29:36.616795Z","end":"2026-05-13T16:29:36.82058Z","steps":["trace[746661950] 'process raft request' (duration: 203.532498ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:43.891897Z","caller":"traceutil/trace.go:171","msg":"trace[485607684] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"106.206993ms","start":"2026-05-13T16:29:43.785665Z","end":"2026-05-13T16:29:43.891872Z","steps":["trace[485607684] 'process raft request' (duration: 106.06396ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.064796Z","caller":"traceutil/trace.go:171","msg":"trace[997948801] linearizableReadLoop","detail":"{readStateIndex:27397; appliedIndex:27393; }","duration":"232.638653ms","start":"2026-05-13T16:29:43.832139Z","end":"2026-05-13T16:29:44.064778Z","steps":["trace[997948801] 'read index received' (duration: 59.552248ms)","trace[997948801] 'applied index is now lower than readState.Index' (duration: 173.085625ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:44.064989Z","caller":"traceutil/trace.go:171","msg":"trace[1564162302] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"266.889987ms","start":"2026-05-13T16:29:43.798068Z","end":"2026-05-13T16:29:44.064958Z","steps":["trace[1564162302] 'process raft request' (duration: 266.683713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.065203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.051694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-13T16:29:44.065241Z","caller":"traceutil/trace.go:171","msg":"trace[1423530487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26521; }","duration":"233.097715ms","start":"2026-05-13T16:29:43.832132Z","end":"2026-05-13T16:29:44.06523Z","steps":["trace[1423530487] 'agreement among raft nodes before linearized reading' (duration: 232.964701ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.065256Z","caller":"traceutil/trace.go:171","msg":"trace[1681961685] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"271.402003ms","start":"2026-05-13T16:29:43.793842Z","end":"2026-05-13T16:29:44.065244Z","steps":["trace[1681961685] 'process raft request' (duration: 270.770999ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.065398Z","caller":"traceutil/trace.go:171","msg":"trace[1157768178] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"268.346018ms","start":"2026-05-13T16:29:43.797042Z","end":"2026-05-13T16:29:44.065388Z","steps":["trace[1157768178] 'process raft request' (duration: 267.677293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.373905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.051565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:29:44.374596Z","caller":"traceutil/trace.go:171","msg":"trace[1300713864] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"158.336955ms","start":"2026-05-13T16:29:44.216233Z","end":"2026-05-13T16:29:44.374569Z","steps":["trace[1300713864] 'process raft request' (duration: 157.891615ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.374682Z","caller":"traceutil/trace.go:171","msg":"trace[983461740] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"305.310598ms","start":"2026-05-13T16:29:44.069327Z","end":"2026-05-13T16:29:44.374638Z","steps":["trace[983461740] 'process raft request' (duration: 114.430466ms)","trace[983461740] 'compare' (duration: 189.863011ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:44.374809Z","caller":"traceutil/trace.go:171","msg":"trace[948184846] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"298.146567ms","start":"2026-05-13T16:29:44.076639Z","end":"2026-05-13T16:29:44.374785Z","steps":["trace[948184846] 'process raft request' (duration: 297.393381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.374842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:44.069316Z","time spent":"305.445742ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:44.688438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.00057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-05-13T16:29:44.688439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.177107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-13T16:29:44.68852Z","caller":"traceutil/trace.go:171","msg":"trace[893332739] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26525; }","duration":"178.128003ms","start":"2026-05-13T16:29:44.510372Z","end":"2026-05-13T16:29:44.6885Z","steps":["trace[893332739] 'range keys from in-memory index tree' (duration: 177.812617ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.688547Z","caller":"traceutil/trace.go:171","msg":"trace[2136107187] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26525; }","duration":"208.31792ms","start":"2026-05-13T16:29:44.480204Z","end":"2026-05-13T16:29:44.688522Z","steps":["trace[2136107187] 'range keys from in-memory index tree' (duration: 207.923442ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.977898Z","caller":"traceutil/trace.go:171","msg":"trace[946396959] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"342.78629ms","start":"2026-05-13T16:29:44.635085Z","end":"2026-05-13T16:29:44.977871Z","steps":["trace[946396959] 'process raft request' (duration: 342.644567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.978029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:44.635061Z","time spent":"342.898242ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:44.984057Z","caller":"traceutil/trace.go:171","msg":"trace[1347240852] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"346.331896ms","start":"2026-05-13T16:29:44.637699Z","end":"2026-05-13T16:29:44.984031Z","steps":["trace[1347240852] 'process raft request' (duration: 346.156031ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.984187Z","caller":"traceutil/trace.go:171","msg":"trace[1390322282] linearizableReadLoop","detail":"{readStateIndex:27405; appliedIndex:27402; }","duration":"321.228024ms","start":"2026-05-13T16:29:44.662939Z","end":"2026-05-13T16:29:44.984167Z","steps":["trace[1390322282] 'read index received' (duration: 314.846599ms)","trace[1390322282] 'applied index is now lower than readState.Index' (duration: 6.378655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:44.984201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:44.637673Z","time spent":"346.452847ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:29:44.984261Z","caller":"traceutil/trace.go:171","msg":"trace[529834648] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"342.522645ms","start":"2026-05-13T16:29:44.641723Z","end":"2026-05-13T16:29:44.984246Z","steps":["trace[529834648] 'process raft request' (duration: 342.265179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.984339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:44.641687Z","time spent":"342.606717ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:29:44.984341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.383748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-13T16:29:44.98436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.944061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-13T16:29:44.984367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.648301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-13T16:29:44.984368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.937176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-13T16:29:44.984389Z","caller":"traceutil/trace.go:171","msg":"trace[663101584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26528; }","duration":"135.005132ms","start":"2026-05-13T16:29:44.849376Z","end":"2026-05-13T16:29:44.984382Z","steps":["trace[663101584] 'agreement among raft nodes before linearized reading' (duration: 134.90335ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.984389Z","caller":"traceutil/trace.go:171","msg":"trace[918316092] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26528; }","duration":"321.47835ms","start":"2026-05-13T16:29:44.662899Z","end":"2026-05-13T16:29:44.984377Z","steps":["trace[918316092] 'agreement among raft nodes before linearized reading' (duration: 321.375178ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.984399Z","caller":"traceutil/trace.go:171","msg":"trace[1285218661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26528; }","duration":"176.717482ms","start":"2026-05-13T16:29:44.807672Z","end":"2026-05-13T16:29:44.98439Z","steps":["trace[1285218661] 'agreement among raft nodes before linearized reading' (duration: 176.664091ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:44.984406Z","caller":"traceutil/trace.go:171","msg":"trace[170438469] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26528; }","duration":"291.996318ms","start":"2026-05-13T16:29:44.692399Z","end":"2026-05-13T16:29:44.984395Z","steps":["trace[170438469] 'agreement among raft nodes before linearized reading' (duration: 291.880196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:44.984416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:29:44.662885Z","time spent":"321.526101ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-13T16:29:44.984453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.009938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-13T16:29:44.98453Z","caller":"traceutil/trace.go:171","msg":"trace[1432055135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26528; }","duration":"132.131881ms","start":"2026-05-13T16:29:44.852382Z","end":"2026-05-13T16:29:44.984514Z","steps":["trace[1432055135] 'agreement among raft nodes before linearized reading' (duration: 131.928586ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:58.493935Z","caller":"traceutil/trace.go:171","msg":"trace[951195399] transaction","detail":"{read_only:false; response_revision:26644; number_of_response:1; }","duration":"296.734888ms","start":"2026-05-13T16:29:58.197172Z","end":"2026-05-13T16:29:58.493907Z","steps":["trace[951195399] 'process raft request' (duration: 198.695227ms)","trace[951195399] 'compare' (duration: 97.859217ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:29:58.494091Z","caller":"traceutil/trace.go:171","msg":"trace[1162526602] transaction","detail":"{read_only:false; response_revision:26645; number_of_response:1; }","duration":"296.337429ms","start":"2026-05-13T16:29:58.197733Z","end":"2026-05-13T16:29:58.49407Z","steps":["trace[1162526602] 'process raft request' (duration: 296.123254ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:58.494081Z","caller":"traceutil/trace.go:171","msg":"trace[2047065781] linearizableReadLoop","detail":"{readStateIndex:27524; appliedIndex:27522; }","duration":"291.063688ms","start":"2026-05-13T16:29:58.202989Z","end":"2026-05-13T16:29:58.494053Z","steps":["trace[2047065781] 'read index received' (duration: 60.0994ms)","trace[2047065781] 'applied index is now lower than readState.Index' (duration: 230.963118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:29:58.494221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.209661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:29:58.494272Z","caller":"traceutil/trace.go:171","msg":"trace[843721632] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26645; }","duration":"291.338394ms","start":"2026-05-13T16:29:58.202921Z","end":"2026-05-13T16:29:58.494259Z","steps":["trace[843721632] 'agreement among raft nodes before linearized reading' (duration: 291.207761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:29:58.510903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.937595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-13T16:29:58.511005Z","caller":"traceutil/trace.go:171","msg":"trace[731115205] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26646; }","duration":"158.077058ms","start":"2026-05-13T16:29:58.35291Z","end":"2026-05-13T16:29:58.510987Z","steps":["trace[731115205] 'agreement among raft nodes before linearized reading' (duration: 157.896625ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:29:59.501003Z","caller":"traceutil/trace.go:171","msg":"trace[1442989562] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"108.649885ms","start":"2026-05-13T16:29:59.392326Z","end":"2026-05-13T16:29:59.500976Z","steps":["trace[1442989562] 'process raft request' (duration: 108.432781ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.147915Z","caller":"traceutil/trace.go:171","msg":"trace[1573047518] linearizableReadLoop","detail":"{readStateIndex:27578; appliedIndex:27577; }","duration":"122.273612ms","start":"2026-05-13T16:30:04.025624Z","end":"2026-05-13T16:30:04.147898Z","steps":["trace[1573047518] 'read index received' (duration: 122.212021ms)","trace[1573047518] 'applied index is now lower than readState.Index' (duration: 60.731µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:30:04.148013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.371514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:30:04.148032Z","caller":"traceutil/trace.go:171","msg":"trace[815261761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26697; }","duration":"122.439265ms","start":"2026-05-13T16:30:04.025587Z","end":"2026-05-13T16:30:04.148026Z","steps":["trace[815261761] 'agreement among raft nodes before linearized reading' (duration: 122.376494ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.424401Z","caller":"traceutil/trace.go:171","msg":"trace[1137734764] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"366.045621ms","start":"2026-05-13T16:30:04.058334Z","end":"2026-05-13T16:30:04.42438Z","steps":["trace[1137734764] 'process raft request' (duration: 278.676366ms)","trace[1137734764] 'compare' (duration: 87.254613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:30:04.424511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:30:04.05831Z","time spent":"366.141034ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T16:30:04.523032Z","caller":"traceutil/trace.go:171","msg":"trace[890218275] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"238.313073ms","start":"2026-05-13T16:30:04.284693Z","end":"2026-05-13T16:30:04.523006Z","steps":["trace[890218275] 'process raft request' (duration: 238.215691ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.52307Z","caller":"traceutil/trace.go:171","msg":"trace[913849464] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"373.712032ms","start":"2026-05-13T16:30:04.149316Z","end":"2026-05-13T16:30:04.523028Z","steps":["trace[913849464] 'process raft request' (duration: 373.435477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:04.523315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T16:30:04.149288Z","time spent":"373.945608ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T16:30:04.722461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.902572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:30:04.722694Z","caller":"traceutil/trace.go:171","msg":"trace[716821510] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"194.231783ms","start":"2026-05-13T16:30:04.528446Z","end":"2026-05-13T16:30:04.722678Z","steps":["trace[716821510] 'process raft request' (duration: 194.180472ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.722816Z","caller":"traceutil/trace.go:171","msg":"trace[1244973183] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"214.050051ms","start":"2026-05-13T16:30:04.508745Z","end":"2026-05-13T16:30:04.722795Z","steps":["trace[1244973183] 'process raft request' (duration: 213.778926ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.722856Z","caller":"traceutil/trace.go:171","msg":"trace[1401116253] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"212.431566ms","start":"2026-05-13T16:30:04.510416Z","end":"2026-05-13T16:30:04.722848Z","steps":["trace[1401116253] 'process raft request' (duration: 212.176511ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:04.72303Z","caller":"traceutil/trace.go:171","msg":"trace[301234703] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"216.753809ms","start":"2026-05-13T16:30:04.506254Z","end":"2026-05-13T16:30:04.723008Z","steps":["trace[301234703] 'process raft request' (duration: 87.255903ms)","trace[301234703] 'compare' (duration: 128.622987ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:30:04.723154Z","caller":"traceutil/trace.go:171","msg":"trace[57324860] linearizableReadLoop","detail":"{readStateIndex:27584; appliedIndex:27579; }","duration":"203.199782ms","start":"2026-05-13T16:30:04.519668Z","end":"2026-05-13T16:30:04.722868Z","steps":["trace[57324860] 'read index received' (duration: 3.064344ms)","trace[57324860] 'applied index is now lower than readState.Index' (duration: 200.133528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:30:04.723221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.537909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-13T16:30:04.723257Z","caller":"traceutil/trace.go:171","msg":"trace[738647020] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26704; }","duration":"203.614471ms","start":"2026-05-13T16:30:04.519632Z","end":"2026-05-13T16:30:04.723246Z","steps":["trace[738647020] 'agreement among raft nodes before linearized reading' (duration: 203.534879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:04.723365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.540364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-13T16:30:04.723403Z","caller":"traceutil/trace.go:171","msg":"trace[1251897109] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26704; }","duration":"198.601385ms","start":"2026-05-13T16:30:04.524791Z","end":"2026-05-13T16:30:04.723392Z","steps":["trace[1251897109] 'agreement among raft nodes before linearized reading' (duration: 198.467802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:04.723465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.234892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-13T16:30:04.723537Z","caller":"traceutil/trace.go:171","msg":"trace[861568083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26704; }","duration":"131.376755ms","start":"2026-05-13T16:30:04.592142Z","end":"2026-05-13T16:30:04.723518Z","steps":["trace[861568083] 'agreement among raft nodes before linearized reading' (duration: 131.172011ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:05.979351Z","caller":"traceutil/trace.go:171","msg":"trace[1167281937] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"191.717939ms","start":"2026-05-13T16:30:05.787607Z","end":"2026-05-13T16:30:05.979325Z","steps":["trace[1167281937] 'process raft request' (duration: 191.565156ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:05.981793Z","caller":"traceutil/trace.go:171","msg":"trace[1959976007] linearizableReadLoop","detail":"{readStateIndex:27597; appliedIndex:27595; }","duration":"173.989435ms","start":"2026-05-13T16:30:05.80778Z","end":"2026-05-13T16:30:05.981769Z","steps":["trace[1959976007] 'read index received' (duration: 171.433501ms)","trace[1959976007] 'applied index is now lower than readState.Index' (duration: 2.554634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:30:05.98196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.161798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:30:05.982034Z","caller":"traceutil/trace.go:171","msg":"trace[102303386] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"185.047649ms","start":"2026-05-13T16:30:05.796816Z","end":"2026-05-13T16:30:05.981863Z","steps":["trace[102303386] 'process raft request' (duration: 184.812684ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:05.982055Z","caller":"traceutil/trace.go:171","msg":"trace[533850121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26716; }","duration":"174.308062ms","start":"2026-05-13T16:30:05.807732Z","end":"2026-05-13T16:30:05.98204Z","steps":["trace[533850121] 'agreement among raft nodes before linearized reading' (duration: 174.131668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:10.81472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.457114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-13T16:30:10.814836Z","caller":"traceutil/trace.go:171","msg":"trace[1457968233] linearizableReadLoop","detail":"{readStateIndex:27635; appliedIndex:27634; }","duration":"247.912374ms","start":"2026-05-13T16:30:10.566904Z","end":"2026-05-13T16:30:10.814816Z","steps":["trace[1457968233] 'read index received' (duration: 103.302087ms)","trace[1457968233] 'applied index is now lower than readState.Index' (duration: 144.606837ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:30:10.814867Z","caller":"traceutil/trace.go:171","msg":"trace[1285276453] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"273.95582ms","start":"2026-05-13T16:30:10.540877Z","end":"2026-05-13T16:30:10.814833Z","steps":["trace[1285276453] 'process raft request' (duration: 129.306632ms)","trace[1285276453] 'compare' (duration: 144.085917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T16:30:10.815011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.100278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:30:10.81507Z","caller":"traceutil/trace.go:171","msg":"trace[210295392] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26753; }","duration":"248.187259ms","start":"2026-05-13T16:30:10.566866Z","end":"2026-05-13T16:30:10.815054Z","steps":["trace[210295392] 'agreement among raft nodes before linearized reading' (duration: 248.043626ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:10.875899Z","caller":"traceutil/trace.go:171","msg":"trace[66649553] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"133.686235ms","start":"2026-05-13T16:30:10.742193Z","end":"2026-05-13T16:30:10.875879Z","steps":["trace[66649553] 'process raft request' (duration: 133.580683ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:10.879933Z","caller":"traceutil/trace.go:171","msg":"trace[17871064] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"135.971384ms","start":"2026-05-13T16:30:10.743931Z","end":"2026-05-13T16:30:10.879902Z","steps":["trace[17871064] 'process raft request' (duration: 135.746438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:10.880045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.671351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-13T16:30:10.880106Z","caller":"traceutil/trace.go:171","msg":"trace[430177071] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:26756; }","duration":"263.769524ms","start":"2026-05-13T16:30:10.61632Z","end":"2026-05-13T16:30:10.88009Z","steps":["trace[430177071] 'agreement among raft nodes before linearized reading' (duration: 263.669592ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:10.880292Z","caller":"traceutil/trace.go:171","msg":"trace[398680571] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"131.115ms","start":"2026-05-13T16:30:10.749166Z","end":"2026-05-13T16:30:10.880282Z","steps":["trace[398680571] 'process raft request' (duration: 130.643359ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:13.241587Z","caller":"traceutil/trace.go:171","msg":"trace[1216479786] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"165.644088ms","start":"2026-05-13T16:30:13.075905Z","end":"2026-05-13T16:30:13.241549Z","steps":["trace[1216479786] 'process raft request' (duration: 77.20977ms)","trace[1216479786] 'compare' (duration: 88.010448ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:30:13.241717Z","caller":"traceutil/trace.go:171","msg":"trace[1318880391] linearizableReadLoop","detail":"{readStateIndex:27657; appliedIndex:27655; }","duration":"129.972746ms","start":"2026-05-13T16:30:13.111715Z","end":"2026-05-13T16:30:13.241688Z","steps":["trace[1318880391] 'read index received' (duration: 41.331123ms)","trace[1318880391] 'applied index is now lower than readState.Index' (duration: 88.639853ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T16:30:13.24176Z","caller":"traceutil/trace.go:171","msg":"trace[789814972] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"164.424581ms","start":"2026-05-13T16:30:13.077315Z","end":"2026-05-13T16:30:13.241739Z","steps":["trace[789814972] 'process raft request' (duration: 164.143425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:13.242009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.893452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-13T16:30:13.242055Z","caller":"traceutil/trace.go:171","msg":"trace[1122886689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26775; }","duration":"128.971434ms","start":"2026-05-13T16:30:13.113069Z","end":"2026-05-13T16:30:13.242041Z","steps":["trace[1122886689] 'agreement among raft nodes before linearized reading' (duration: 128.78692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T16:30:13.242216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.496577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-13T16:30:13.242278Z","caller":"traceutil/trace.go:171","msg":"trace[756899766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26775; }","duration":"130.612599ms","start":"2026-05-13T16:30:13.111649Z","end":"2026-05-13T16:30:13.242262Z","steps":["trace[756899766] 'agreement among raft nodes before linearized reading' (duration: 130.18692ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:13.432503Z","caller":"traceutil/trace.go:171","msg":"trace[1645091368] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"185.980042ms","start":"2026-05-13T16:30:13.246485Z","end":"2026-05-13T16:30:13.432465Z","steps":["trace[1645091368] 'process raft request' (duration: 185.851469ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:30:13.432724Z","caller":"traceutil/trace.go:171","msg":"trace[308343853] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"186.722958ms","start":"2026-05-13T16:30:13.245978Z","end":"2026-05-13T16:30:13.432701Z","steps":["trace[308343853] 'process raft request' (duration: 186.199607ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T16:31:51.057227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24912} {"level":"info","ts":"2026-05-13T16:31:51.192155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24912,"took":"130.808493ms","hash":1670386272,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":36360192,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-13T16:31:51.192296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1670386272,"revision":24912,"compact-revision":21642} {"level":"info","ts":"2026-05-13T16:34:01.904799Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-13T16:34:01.910055Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-13T16:34:01.910143Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-13T16:36:51.06406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27747} {"level":"info","ts":"2026-05-13T16:36:51.183233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27747,"took":"114.965155ms","hash":1689195774,"current-db-size-bytes":59326464,"current-db-size":"59 MB","current-db-size-in-use-bytes":36851712,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-13T16:36:51.183304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1689195774,"revision":27747,"compact-revision":24912} {"level":"info","ts":"2026-05-13T16:41:51.081204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30397} {"level":"info","ts":"2026-05-13T16:41:51.215237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30397,"took":"130.654593ms","hash":3353792207,"current-db-size-bytes":59748352,"current-db-size":"60 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-13T16:41:51.215306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3353792207,"revision":30397,"compact-revision":27747} {"level":"info","ts":"2026-05-13T16:46:51.091796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32658} {"level":"info","ts":"2026-05-13T16:46:51.248687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32658,"took":"153.141191ms","hash":584691432,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-13T16:46:51.248739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":584691432,"revision":32658,"compact-revision":30397} {"level":"info","ts":"2026-05-13T16:51:51.097575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34893} {"level":"info","ts":"2026-05-13T16:51:51.252992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34893,"took":"151.535445ms","hash":2578167926,"current-db-size-bytes":61255680,"current-db-size":"61 MB","current-db-size-in-use-bytes":31100928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-13T16:51:51.25308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2578167926,"revision":34893,"compact-revision":32658} {"level":"info","ts":"2026-05-13T16:55:18.559184Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-13T16:55:18.565628Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-13T16:55:18.565745Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-13T16:56:51.106945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37184} {"level":"info","ts":"2026-05-13T16:56:51.23681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37184,"took":"125.006304ms","hash":1057387179,"current-db-size-bytes":61681664,"current-db-size":"62 MB","current-db-size-in-use-bytes":31502336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-13T16:56:51.236869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1057387179,"revision":37184,"compact-revision":34893}