{"level":"warn","ts":"2026-05-30T09:42:27.427684Z","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-30T09:42:27.427786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-30T09:42:27.42786Z","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-30T09:42:27.427869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-30T09:42:27.427885Z","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-30T09:42:27.428555Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-30T09:42:27.428631Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-30T09:42:27.43665Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.713714ms"} {"level":"info","ts":"2026-05-30T09:42:27.444424Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-30T09:42:27.444528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:42:27.444551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-30T09:42:27.44457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:42:27.444583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-30T09:42:27.444634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-30T09:42:27.453463Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:42:27.455039Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:42:27.456224Z","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-30T09:42:27.458565Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:42:27.458764Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:42:27.458968Z","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-30T09:42:27.459075Z","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-30T09:42:27.4591Z","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-30T09:42:27.462784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-30T09:42:27.464317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-30T09:42:27.466343Z","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-30T09:42:27.466451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-30T09:42:27.466515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-30T09:42:27.466634Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:42:27.466691Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:42:27.846949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:42:27.847049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:42:27.84709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-30T09:42:27.847295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:42:27.847306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-30T09:42:27.847325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-30T09:42:27.847339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-30T09:42:27.855281Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:42:27.855465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:42:27.855675Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:27.856032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:42:27.856606Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:27.856851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:27.856899Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:27.857395Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:42:27.857426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:42:27.857713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-30T09:42:27.859583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T09:42:30.938724Z","caller":"traceutil/trace.go:171","msg":"trace[654334396] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"205.833692ms","start":"2026-05-30T09:42:30.732853Z","end":"2026-05-30T09:42:30.938687Z","steps":["trace[654334396] 'process raft request' (duration: 143.145232ms)","trace[654334396] 'compare' (duration: 62.524598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:31.681264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.807874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:42:31.68136Z","caller":"traceutil/trace.go:171","msg":"trace[657970630] linearizableReadLoop","detail":"{readStateIndex:169; appliedIndex:168; }","duration":"488.093211ms","start":"2026-05-30T09:42:31.193255Z","end":"2026-05-30T09:42:31.681348Z","steps":["trace[657970630] 'read index received' (duration: 103.716551ms)","trace[657970630] 'applied index is now lower than readState.Index' (duration: 384.37575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:31.681434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.173392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:31.68146Z","caller":"traceutil/trace.go:171","msg":"trace[469562652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"488.203132ms","start":"2026-05-30T09:42:31.193248Z","end":"2026-05-30T09:42:31.681452Z","steps":["trace[469562652] 'agreement among raft nodes before linearized reading' (duration: 488.132161ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:31.681442Z","caller":"traceutil/trace.go:171","msg":"trace[1242747270] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"696.649287ms","start":"2026-05-30T09:42:30.98474Z","end":"2026-05-30T09:42:31.68139Z","steps":["trace[1242747270] 'process raft request' (duration: 312.344698ms)","trace[1242747270] 'compare' (duration: 383.667533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:31.681489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:31.193176Z","time spent":"488.307233ms","remote":"127.0.0.1:52106","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-30T09:42:31.681688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:30.984727Z","time spent":"696.855139ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:42:32.44264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.130189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:42:32.442759Z","caller":"traceutil/trace.go:171","msg":"trace[1396206823] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"757.050183ms","start":"2026-05-30T09:42:31.68569Z","end":"2026-05-30T09:42:32.44274Z","steps":["trace[1396206823] 'process raft request' (duration: 282.736612ms)","trace[1396206823] 'compare' (duration: 473.987498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:32.442833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:31.685674Z","time spent":"757.118944ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:42:32.541993Z","caller":"traceutil/trace.go:171","msg":"trace[1255227828] linearizableReadLoop","detail":"{readStateIndex:171; appliedIndex:170; }","duration":"249.849473ms","start":"2026-05-30T09:42:32.292118Z","end":"2026-05-30T09:42:32.541968Z","steps":["trace[1255227828] 'read index received' (duration: 249.737382ms)","trace[1255227828] 'applied index is now lower than readState.Index' (duration: 111.321µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:32.542104Z","caller":"traceutil/trace.go:171","msg":"trace[2116891614] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"743.170848ms","start":"2026-05-30T09:42:31.798902Z","end":"2026-05-30T09:42:32.542073Z","steps":["trace[2116891614] 'process raft request' (duration: 742.954856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:32.542184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.027804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:32.542237Z","caller":"traceutil/trace.go:171","msg":"trace[394914335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:167; }","duration":"250.143606ms","start":"2026-05-30T09:42:32.29208Z","end":"2026-05-30T09:42:32.542224Z","steps":["trace[394914335] 'agreement among raft nodes before linearized reading' (duration: 250.005365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:32.542231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:31.798829Z","time spent":"743.33771ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:42:37.904744Z","caller":"traceutil/trace.go:171","msg":"trace[1047719785] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"103.864638ms","start":"2026-05-30T09:42:37.800845Z","end":"2026-05-30T09:42:37.904709Z","steps":["trace[1047719785] 'process raft request' (duration: 103.551204ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.284723Z","caller":"traceutil/trace.go:171","msg":"trace[2132474418] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"158.739377ms","start":"2026-05-30T09:42:38.125958Z","end":"2026-05-30T09:42:38.284698Z","steps":["trace[2132474418] 'process raft request' (duration: 158.671706ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.284865Z","caller":"traceutil/trace.go:171","msg":"trace[1726484481] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"160.490345ms","start":"2026-05-30T09:42:38.124338Z","end":"2026-05-30T09:42:38.284828Z","steps":["trace[1726484481] 'process raft request' (duration: 94.205014ms)","trace[1726484481] 'compare' (duration: 65.899097ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:38.302538Z","caller":"traceutil/trace.go:171","msg":"trace[1582673934] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:332; }","duration":"160.037292ms","start":"2026-05-30T09:42:38.142462Z","end":"2026-05-30T09:42:38.3025Z","steps":["trace[1582673934] 'read index received' (duration: 76.065921ms)","trace[1582673934] 'applied index is now lower than readState.Index' (duration: 83.969691ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:38.302731Z","caller":"traceutil/trace.go:171","msg":"trace[1187527791] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"167.096943ms","start":"2026-05-30T09:42:38.135606Z","end":"2026-05-30T09:42:38.302703Z","steps":["trace[1187527791] 'process raft request' (duration: 166.674898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.302766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.278034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-30T09:42:38.303001Z","caller":"traceutil/trace.go:171","msg":"trace[3062168] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:323; }","duration":"160.582706ms","start":"2026-05-30T09:42:38.142396Z","end":"2026-05-30T09:42:38.302979Z","steps":["trace[3062168] 'agreement among raft nodes before linearized reading' (duration: 160.283293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.354099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.039621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-30T09:42:38.354208Z","caller":"traceutil/trace.go:171","msg":"trace[1198302874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:324; }","duration":"160.211083ms","start":"2026-05-30T09:42:38.193969Z","end":"2026-05-30T09:42:38.35418Z","steps":["trace[1198302874] 'agreement among raft nodes before linearized reading' (duration: 160.010441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.354235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.305387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-30T09:42:38.354326Z","caller":"traceutil/trace.go:171","msg":"trace[1294624918] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:324; }","duration":"109.458159ms","start":"2026-05-30T09:42:38.244845Z","end":"2026-05-30T09:42:38.354303Z","steps":["trace[1294624918] 'agreement among raft nodes before linearized reading' (duration: 109.258897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.354539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.196646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-30T09:42:38.354838Z","caller":"traceutil/trace.go:171","msg":"trace[527257097] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:324; }","duration":"109.519779ms","start":"2026-05-30T09:42:38.2453Z","end":"2026-05-30T09:42:38.35482Z","steps":["trace[527257097] 'agreement among raft nodes before linearized reading' (duration: 109.193526ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.511116Z","caller":"traceutil/trace.go:171","msg":"trace[1226471283] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"154.907799ms","start":"2026-05-30T09:42:38.356178Z","end":"2026-05-30T09:42:38.511086Z","steps":["trace[1226471283] 'read index received' (duration: 128.157848ms)","trace[1226471283] 'applied index is now lower than readState.Index' (duration: 26.748321ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:38.51126Z","caller":"traceutil/trace.go:171","msg":"trace[997049794] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"198.44377ms","start":"2026-05-30T09:42:38.3128Z","end":"2026-05-30T09:42:38.511244Z","steps":["trace[997049794] 'process raft request' (duration: 171.514417ms)","trace[997049794] 'compare' (duration: 26.61303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:38.511559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.360593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-5qgr9\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-05-30T09:42:38.51167Z","caller":"traceutil/trace.go:171","msg":"trace[809715425] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-5qgr9; range_end:; response_count:1; response_revision:325; }","duration":"155.527874ms","start":"2026-05-30T09:42:38.356115Z","end":"2026-05-30T09:42:38.511643Z","steps":["trace[809715425] 'agreement among raft nodes before linearized reading' (duration: 155.15524ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.589174Z","caller":"traceutil/trace.go:171","msg":"trace[436084642] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"224.870487ms","start":"2026-05-30T09:42:38.364271Z","end":"2026-05-30T09:42:38.589142Z","steps":["trace[436084642] 'process raft request' (duration: 224.611265ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.589411Z","caller":"traceutil/trace.go:171","msg":"trace[1818423607] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"223.685245ms","start":"2026-05-30T09:42:38.365698Z","end":"2026-05-30T09:42:38.589383Z","steps":["trace[1818423607] 'process raft request' (duration: 223.350172ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.589545Z","caller":"traceutil/trace.go:171","msg":"trace[958258943] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"222.972818ms","start":"2026-05-30T09:42:38.366543Z","end":"2026-05-30T09:42:38.589515Z","steps":["trace[958258943] 'process raft request' (duration: 222.619434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.589712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.602622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-30T09:42:38.589776Z","caller":"traceutil/trace.go:171","msg":"trace[1066468582] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:329; }","duration":"132.738643ms","start":"2026-05-30T09:42:38.457023Z","end":"2026-05-30T09:42:38.589762Z","steps":["trace[1066468582] 'agreement among raft nodes before linearized reading' (duration: 132.577432ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.590023Z","caller":"traceutil/trace.go:171","msg":"trace[457148733] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"222.833717ms","start":"2026-05-30T09:42:38.366386Z","end":"2026-05-30T09:42:38.58922Z","steps":["trace[457148733] 'process raft request' (duration: 222.726886ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.670171Z","caller":"traceutil/trace.go:171","msg":"trace[1771830761] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"153.659727ms","start":"2026-05-30T09:42:38.516465Z","end":"2026-05-30T09:42:38.670125Z","steps":["trace[1771830761] 'process raft request' (duration: 152.707876ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:38.670195Z","caller":"traceutil/trace.go:171","msg":"trace[900692020] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"150.498915ms","start":"2026-05-30T09:42:38.519671Z","end":"2026-05-30T09:42:38.67017Z","steps":["trace[900692020] 'process raft request' (duration: 150.393894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:38.67027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.916979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-30T09:42:38.670665Z","caller":"traceutil/trace.go:171","msg":"trace[46253050] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:331; }","duration":"155.379754ms","start":"2026-05-30T09:42:38.515263Z","end":"2026-05-30T09:42:38.670643Z","steps":["trace[46253050] 'agreement among raft nodes before linearized reading' (duration: 154.906899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:47.015511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.779615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:47.015598Z","caller":"traceutil/trace.go:171","msg":"trace[741274252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"100.915647ms","start":"2026-05-30T09:42:46.914662Z","end":"2026-05-30T09:42:47.015578Z","steps":["trace[741274252] 'range keys from in-memory index tree' (duration: 100.717245ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:47.244058Z","caller":"traceutil/trace.go:171","msg":"trace[187702091] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"172.077509ms","start":"2026-05-30T09:42:47.071953Z","end":"2026-05-30T09:42:47.24403Z","steps":["trace[187702091] 'process raft request' (duration: 171.903187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:47.638155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.502417ms","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-30T09:42:47.638234Z","caller":"traceutil/trace.go:171","msg":"trace[389274590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"134.626868ms","start":"2026-05-30T09:42:47.503589Z","end":"2026-05-30T09:42:47.638216Z","steps":["trace[389274590] 'range keys from in-memory index tree' (duration: 134.306435ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:47.755763Z","caller":"traceutil/trace.go:171","msg":"trace[1546197685] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"113.608335ms","start":"2026-05-30T09:42:47.642124Z","end":"2026-05-30T09:42:47.755732Z","steps":["trace[1546197685] 'process raft request' (duration: 113.444593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:48.154103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.95301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:42:48.154617Z","caller":"traceutil/trace.go:171","msg":"trace[1024785334] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"511.251091ms","start":"2026-05-30T09:42:47.643338Z","end":"2026-05-30T09:42:48.154589Z","steps":["trace[1024785334] 'process raft request' (duration: 336.547724ms)","trace[1024785334] 'compare' (duration: 173.752857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:48.154933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.64331Z","time spent":"511.514124ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:42:48.529181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.942347ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-30T09:42:48.89205Z","caller":"traceutil/trace.go:171","msg":"trace[82903705] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"122.556306ms","start":"2026-05-30T09:42:48.76947Z","end":"2026-05-30T09:42:48.892026Z","steps":["trace[82903705] 'process raft request' (duration: 122.382465ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:49.467239Z","caller":"traceutil/trace.go:171","msg":"trace[1966093310] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"214.532478ms","start":"2026-05-30T09:42:49.252682Z","end":"2026-05-30T09:42:49.467215Z","steps":["trace[1966093310] 'process raft request' (duration: 214.362357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:50.12782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.891757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:50.127929Z","caller":"traceutil/trace.go:171","msg":"trace[1723892595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"213.018758ms","start":"2026-05-30T09:42:49.914888Z","end":"2026-05-30T09:42:50.127906Z","steps":["trace[1723892595] 'range keys from in-memory index tree' (duration: 212.817427ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:50.279358Z","caller":"traceutil/trace.go:171","msg":"trace[1874385806] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"149.896915ms","start":"2026-05-30T09:42:50.12941Z","end":"2026-05-30T09:42:50.279307Z","steps":["trace[1874385806] 'read index received' (duration: 145.20454ms)","trace[1874385806] 'applied index is now lower than readState.Index' (duration: 4.691505ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:50.279488Z","caller":"traceutil/trace.go:171","msg":"trace[966669654] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"339.325178ms","start":"2026-05-30T09:42:49.940128Z","end":"2026-05-30T09:42:50.279453Z","steps":["trace[966669654] 'process raft request' (duration: 339.042855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:50.280179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.537056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T09:42:50.280242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:49.940102Z","time spent":"339.991544ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:42:50.280263Z","caller":"traceutil/trace.go:171","msg":"trace[2059570491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:387; }","duration":"119.671257ms","start":"2026-05-30T09:42:50.160573Z","end":"2026-05-30T09:42:50.280245Z","steps":["trace[2059570491] 'agreement among raft nodes before linearized reading' (duration: 119.517336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:50.279615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.172718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:50.280483Z","caller":"traceutil/trace.go:171","msg":"trace[1166444883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"150.967435ms","start":"2026-05-30T09:42:50.129374Z","end":"2026-05-30T09:42:50.280341Z","steps":["trace[1166444883] 'agreement among raft nodes before linearized reading' (duration: 150.165198ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:55.691057Z","caller":"traceutil/trace.go:171","msg":"trace[1863053531] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"121.121976ms","start":"2026-05-30T09:42:55.569911Z","end":"2026-05-30T09:42:55.691033Z","steps":["trace[1863053531] 'process raft request' (duration: 120.934795ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:57.33285Z","caller":"traceutil/trace.go:171","msg":"trace[2038779095] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"122.430935ms","start":"2026-05-30T09:42:57.210395Z","end":"2026-05-30T09:42:57.332826Z","steps":["trace[2038779095] 'process raft request' (duration: 122.278089ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:08.695641Z","caller":"traceutil/trace.go:171","msg":"trace[2036372214] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"226.626618ms","start":"2026-05-30T09:43:08.468982Z","end":"2026-05-30T09:43:08.695609Z","steps":["trace[2036372214] 'read index received' (duration: 226.477673ms)","trace[2036372214] 'applied index is now lower than readState.Index' (duration: 148.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:08.69569Z","caller":"traceutil/trace.go:171","msg":"trace[1876692836] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"288.914823ms","start":"2026-05-30T09:43:08.406754Z","end":"2026-05-30T09:43:08.695669Z","steps":["trace[1876692836] 'process raft request' (duration: 288.688385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:08.865691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.686495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-05-30T09:43:08.865771Z","caller":"traceutil/trace.go:171","msg":"trace[200998982] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:459; }","duration":"396.815299ms","start":"2026-05-30T09:43:08.468936Z","end":"2026-05-30T09:43:08.865752Z","steps":["trace[200998982] 'agreement among raft nodes before linearized reading' (duration: 226.789744ms)","trace[200998982] 'range keys from in-memory index tree' (duration: 169.84471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:08.865824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:08.468916Z","time spent":"396.898053ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12693,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-05-30T09:43:11.512723Z","caller":"traceutil/trace.go:171","msg":"trace[1702170798] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"387.194226ms","start":"2026-05-30T09:43:11.125504Z","end":"2026-05-30T09:43:11.512698Z","steps":["trace[1702170798] 'read index received' (duration: 387.01839ms)","trace[1702170798] 'applied index is now lower than readState.Index' (duration: 175.006µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:11.512875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.353322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:11.512912Z","caller":"traceutil/trace.go:171","msg":"trace[1276814204] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:500; }","duration":"387.437484ms","start":"2026-05-30T09:43:11.125462Z","end":"2026-05-30T09:43:11.512899Z","steps":["trace[1276814204] 'agreement among raft nodes before linearized reading' (duration: 387.336451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:11.512952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:11.125444Z","time spent":"387.500166ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-30T09:43:11.51302Z","caller":"traceutil/trace.go:171","msg":"trace[118835212] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"396.060387ms","start":"2026-05-30T09:43:11.11694Z","end":"2026-05-30T09:43:11.513Z","steps":["trace[118835212] 'process raft request' (duration: 395.641523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:11.513126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:11.116922Z","time spent":"396.147169ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:43:11.862018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.402154ms","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-30T09:43:11.862139Z","caller":"traceutil/trace.go:171","msg":"trace[1928642635] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"174.965852ms","start":"2026-05-30T09:43:11.687154Z","end":"2026-05-30T09:43:11.86212Z","steps":["trace[1928642635] 'process raft request' (duration: 174.862779ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.862164Z","caller":"traceutil/trace.go:171","msg":"trace[2089024185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"232.59407ms","start":"2026-05-30T09:43:11.629556Z","end":"2026-05-30T09:43:11.86215Z","steps":["trace[2089024185] 'range keys from in-memory index tree' (duration: 232.312301ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.875965Z","caller":"traceutil/trace.go:171","msg":"trace[566654678] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"180.782612ms","start":"2026-05-30T09:43:11.695145Z","end":"2026-05-30T09:43:11.875927Z","steps":["trace[566654678] 'process raft request' (duration: 180.496913ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.87607Z","caller":"traceutil/trace.go:171","msg":"trace[2033123241] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"178.110565ms","start":"2026-05-30T09:43:11.697882Z","end":"2026-05-30T09:43:11.875992Z","steps":["trace[2033123241] 'process raft request' (duration: 177.997401ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876107Z","caller":"traceutil/trace.go:171","msg":"trace[2063577975] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"172.602744ms","start":"2026-05-30T09:43:11.703478Z","end":"2026-05-30T09:43:11.876081Z","steps":["trace[2063577975] 'process raft request' (duration: 172.427009ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876262Z","caller":"traceutil/trace.go:171","msg":"trace[175571857] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"172.698438ms","start":"2026-05-30T09:43:11.703545Z","end":"2026-05-30T09:43:11.876243Z","steps":["trace[175571857] 'process raft request' (duration: 172.385978ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876308Z","caller":"traceutil/trace.go:171","msg":"trace[919942753] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"178.601041ms","start":"2026-05-30T09:43:11.697683Z","end":"2026-05-30T09:43:11.876285Z","steps":["trace[919942753] 'process raft request' (duration: 178.160997ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876513Z","caller":"traceutil/trace.go:171","msg":"trace[781305046] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"172.147769ms","start":"2026-05-30T09:43:11.704354Z","end":"2026-05-30T09:43:11.876502Z","steps":["trace[781305046] 'process raft request' (duration: 171.979314ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876577Z","caller":"traceutil/trace.go:171","msg":"trace[2081380157] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"137.66457ms","start":"2026-05-30T09:43:11.738897Z","end":"2026-05-30T09:43:11.876562Z","steps":["trace[2081380157] 'process raft request' (duration: 137.570287ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876559Z","caller":"traceutil/trace.go:171","msg":"trace[956190627] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"172.862393ms","start":"2026-05-30T09:43:11.703685Z","end":"2026-05-30T09:43:11.876548Z","steps":["trace[956190627] 'process raft request' (duration: 172.337266ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876678Z","caller":"traceutil/trace.go:171","msg":"trace[137344058] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"125.150681ms","start":"2026-05-30T09:43:11.751521Z","end":"2026-05-30T09:43:11.876671Z","steps":["trace[137344058] 'process raft request' (duration: 124.983514ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:11.876606Z","caller":"traceutil/trace.go:171","msg":"trace[748149636] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"165.106888ms","start":"2026-05-30T09:43:11.711488Z","end":"2026-05-30T09:43:11.876595Z","steps":["trace[748149636] 'process raft request' (duration: 164.928592ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:16.862815Z","caller":"traceutil/trace.go:171","msg":"trace[922927570] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"136.571413ms","start":"2026-05-30T09:43:16.726218Z","end":"2026-05-30T09:43:16.862789Z","steps":["trace[922927570] 'process raft request' (duration: 136.421288ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:16.883733Z","caller":"traceutil/trace.go:171","msg":"trace[1566961681] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"113.408423ms","start":"2026-05-30T09:43:16.770262Z","end":"2026-05-30T09:43:16.883671Z","steps":["trace[1566961681] 'process raft request' (duration: 112.761833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:18.199469Z","caller":"traceutil/trace.go:171","msg":"trace[348806710] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"287.360875ms","start":"2026-05-30T09:43:17.912066Z","end":"2026-05-30T09:43:18.199427Z","steps":["trace[348806710] 'read index received' (duration: 286.269401ms)","trace[348806710] 'applied index is now lower than readState.Index' (duration: 1.088214ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:18.199532Z","caller":"traceutil/trace.go:171","msg":"trace[237417526] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"410.40976ms","start":"2026-05-30T09:43:17.789091Z","end":"2026-05-30T09:43:18.1995Z","steps":["trace[237417526] 'process raft request' (duration: 409.558693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:18.199657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.575241ms","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-30T09:43:18.19985Z","caller":"traceutil/trace.go:171","msg":"trace[1870240375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"287.832109ms","start":"2026-05-30T09:43:17.912006Z","end":"2026-05-30T09:43:18.199838Z","steps":["trace[1870240375] 'agreement among raft nodes before linearized reading' (duration: 287.53642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:18.200221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:17.789068Z","time spent":"410.676218ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:43:19.302689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.531934ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:19.302841Z","caller":"traceutil/trace.go:171","msg":"trace[802520016] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:607; }","duration":"400.692028ms","start":"2026-05-30T09:43:18.902118Z","end":"2026-05-30T09:43:19.30281Z","steps":["trace[802520016] 'range keys from in-memory index tree' (duration: 400.509493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.370388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000316401s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-30T09:43:19.370504Z","caller":"traceutil/trace.go:171","msg":"trace[1767735093] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"1.170767348s","start":"2026-05-30T09:43:18.199718Z","end":"2026-05-30T09:43:19.370485Z","steps":["trace[1767735093] 'read index received' (duration: 170.353612ms)","trace[1767735093] 'applied index is now lower than readState.Index' (duration: 1.000412276s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:19.370598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458161113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:19.37063Z","caller":"traceutil/trace.go:171","msg":"trace[36656337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.458220325s","start":"2026-05-30T09:43:17.912401Z","end":"2026-05-30T09:43:19.370621Z","steps":["trace[36656337] 'agreement among raft nodes before linearized reading' (duration: 1.458155583s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.370665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:17.912394Z","time spent":"1.458263526s","remote":"127.0.0.1:52120","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-30T09:43:19.370692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342672068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:43:19.37075Z","caller":"traceutil/trace.go:171","msg":"trace[515608057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"1.342801642s","start":"2026-05-30T09:43:18.027934Z","end":"2026-05-30T09:43:19.370736Z","steps":["trace[515608057] 'agreement among raft nodes before linearized reading' (duration: 1.342593796s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.370785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.027924Z","time spent":"1.342853914s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:43:19.404459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034376423s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:43:19.404997Z","caller":"traceutil/trace.go:171","msg":"trace[1611610008] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.14792495s","start":"2026-05-30T09:43:18.257051Z","end":"2026-05-30T09:43:19.404976Z","steps":["trace[1611610008] 'process raft request' (duration: 1.147836617s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.405146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.257035Z","time spent":"1.148048943s","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:43:19.405146Z","caller":"traceutil/trace.go:171","msg":"trace[675622470] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.201775035s","start":"2026-05-30T09:43:18.203332Z","end":"2026-05-30T09:43:19.405107Z","steps":["trace[675622470] 'process raft request' (duration: 1.201398493s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.40528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.659901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:43:19.405378Z","caller":"traceutil/trace.go:171","msg":"trace[53641277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"538.797206ms","start":"2026-05-30T09:43:18.86656Z","end":"2026-05-30T09:43:19.405357Z","steps":["trace[53641277] 'agreement among raft nodes before linearized reading' (duration: 538.561009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.405428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.866541Z","time spent":"538.874078ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:43:19.405444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.597709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ktcdt\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-30T09:43:19.405469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.203304Z","time spent":"1.202007901s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:43:19.405533Z","caller":"traceutil/trace.go:171","msg":"trace[25669169] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ktcdt; range_end:; response_count:1; response_revision:609; }","duration":"832.768084ms","start":"2026-05-30T09:43:18.572737Z","end":"2026-05-30T09:43:19.405505Z","steps":["trace[25669169] 'agreement among raft nodes before linearized reading' (duration: 832.395943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.405597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.572717Z","time spent":"832.869367ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-ktcdt\" "} {"level":"warn","ts":"2026-05-30T09:43:21.085966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543468277s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:43:21.08625Z","caller":"traceutil/trace.go:171","msg":"trace[1473073668] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"1.609086742s","start":"2026-05-30T09:43:19.477136Z","end":"2026-05-30T09:43:21.086223Z","steps":["trace[1473073668] 'read index received' (duration: 65.090219ms)","trace[1473073668] 'applied index is now lower than readState.Index' (duration: 1.543994683s)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:21.086352Z","caller":"traceutil/trace.go:171","msg":"trace[1428873153] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.673343505s","start":"2026-05-30T09:43:19.412989Z","end":"2026-05-30T09:43:21.086333Z","steps":["trace[1428873153] 'process raft request' (duration: 129.348132ms)","trace[1428873153] 'compare' (duration: 1.543062126s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:21.086439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:19.41297Z","time spent":"1.673417997s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:43:21.086997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666214129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:21.087046Z","caller":"traceutil/trace.go:171","msg":"trace[53262147] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:612; }","duration":"1.666291842s","start":"2026-05-30T09:43:19.42074Z","end":"2026-05-30T09:43:21.087032Z","steps":["trace[53262147] 'agreement among raft nodes before linearized reading' (duration: 1.666180328s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.087082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:19.420732Z","time spent":"1.666341753s","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-30T09:43:21.087184Z","caller":"traceutil/trace.go:171","msg":"trace[748540833] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.664491977s","start":"2026-05-30T09:43:19.42262Z","end":"2026-05-30T09:43:21.087112Z","steps":["trace[748540833] 'process raft request' (duration: 1.663482947s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.087418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:19.422599Z","time spent":"1.664691372s","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:43:21.217992Z","caller":"traceutil/trace.go:171","msg":"trace[2110061917] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"131.664705ms","start":"2026-05-30T09:43:21.086296Z","end":"2026-05-30T09:43:21.21796Z","steps":["trace[2110061917] 'read index received' (duration: 131.309965ms)","trace[2110061917] 'applied index is now lower than readState.Index' (duration: 353.56µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:21.218036Z","caller":"traceutil/trace.go:171","msg":"trace[252422891] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"204.741133ms","start":"2026-05-30T09:43:21.013268Z","end":"2026-05-30T09:43:21.218009Z","steps":["trace[252422891] 'process raft request' (duration: 204.471215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.218147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739864591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:21.218189Z","caller":"traceutil/trace.go:171","msg":"trace[1121607048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.739970363s","start":"2026-05-30T09:43:19.478207Z","end":"2026-05-30T09:43:21.218178Z","steps":["trace[1121607048] 'agreement among raft nodes before linearized reading' (duration: 1.739876431s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.218229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:19.478191Z","time spent":"1.740030095s","remote":"127.0.0.1:52120","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-30T09:43:21.218214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671025917s","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-30T09:43:21.218335Z","caller":"traceutil/trace.go:171","msg":"trace[1197774684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.671221843s","start":"2026-05-30T09:43:19.547088Z","end":"2026-05-30T09:43:21.218309Z","steps":["trace[1197774684] 'agreement among raft nodes before linearized reading' (duration: 1.670940305s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.218396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:19.547058Z","time spent":"1.671328327s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-30T09:43:21.6219Z","caller":"traceutil/trace.go:171","msg":"trace[1035375151] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"134.600312ms","start":"2026-05-30T09:43:21.487225Z","end":"2026-05-30T09:43:21.621826Z","steps":["trace[1035375151] 'process raft request' (duration: 58.151995ms)","trace[1035375151] 'compare' (duration: 75.959333ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:23.131462Z","caller":"traceutil/trace.go:171","msg":"trace[688454901] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"321.85171ms","start":"2026-05-30T09:43:22.80957Z","end":"2026-05-30T09:43:23.131422Z","steps":["trace[688454901] 'process raft request' (duration: 320.912603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:23.13164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:22.809555Z","time spent":"322.001143ms","remote":"127.0.0.1:52182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:43:23.131464Z","caller":"traceutil/trace.go:171","msg":"trace[423430517] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"216.424212ms","start":"2026-05-30T09:43:22.914979Z","end":"2026-05-30T09:43:23.131403Z","steps":["trace[423430517] 'read index received' (duration: 215.473915ms)","trace[423430517] 'applied index is now lower than readState.Index' (duration: 948.217µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:23.132282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.66898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:23.132456Z","caller":"traceutil/trace.go:171","msg":"trace[829481767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"217.535834ms","start":"2026-05-30T09:43:22.914903Z","end":"2026-05-30T09:43:23.132439Z","steps":["trace[829481767] 'agreement among raft nodes before linearized reading' (duration: 216.631608ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:31.663285Z","caller":"traceutil/trace.go:171","msg":"trace[103165488] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"117.564976ms","start":"2026-05-30T09:43:31.545695Z","end":"2026-05-30T09:43:31.66326Z","steps":["trace[103165488] 'process raft request' (duration: 62.506158ms)","trace[103165488] 'compare' (duration: 54.885993ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:37.053686Z","caller":"traceutil/trace.go:171","msg":"trace[722363302] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"327.572994ms","start":"2026-05-30T09:43:36.726085Z","end":"2026-05-30T09:43:37.053658Z","steps":["trace[722363302] 'read index received' (duration: 327.380359ms)","trace[722363302] 'applied index is now lower than readState.Index' (duration: 191.205µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:37.053934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.878693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:37.053813Z","caller":"traceutil/trace.go:171","msg":"trace[130689836] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"337.212763ms","start":"2026-05-30T09:43:36.716534Z","end":"2026-05-30T09:43:37.053747Z","steps":["trace[130689836] 'process raft request' (duration: 336.937586ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:37.054048Z","caller":"traceutil/trace.go:171","msg":"trace[558724838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"138.053947ms","start":"2026-05-30T09:43:36.915968Z","end":"2026-05-30T09:43:37.054022Z","steps":["trace[558724838] 'agreement among raft nodes before linearized reading' (duration: 137.878762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:37.054247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:36.716509Z","time spent":"337.602673ms","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:43:37.054565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.478826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:43:37.054835Z","caller":"traceutil/trace.go:171","msg":"trace[878775953] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:773; }","duration":"328.794514ms","start":"2026-05-30T09:43:36.726025Z","end":"2026-05-30T09:43:37.054819Z","steps":["trace[878775953] 'agreement among raft nodes before linearized reading' (duration: 328.375103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:37.054961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:36.726003Z","time spent":"328.904227ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:43:49.615305Z","caller":"traceutil/trace.go:171","msg":"trace[1619909532] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"233.957084ms","start":"2026-05-30T09:43:49.381324Z","end":"2026-05-30T09:43:49.615281Z","steps":["trace[1619909532] 'read index received' (duration: 233.832011ms)","trace[1619909532] 'applied index is now lower than readState.Index' (duration: 123.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:49.615341Z","caller":"traceutil/trace.go:171","msg":"trace[1669365468] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"234.838944ms","start":"2026-05-30T09:43:49.380465Z","end":"2026-05-30T09:43:49.615304Z","steps":["trace[1669365468] 'process raft request' (duration: 234.699701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:49.61637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.016407ms","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-30T09:43:49.616496Z","caller":"traceutil/trace.go:171","msg":"trace[784394314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"235.192982ms","start":"2026-05-30T09:43:49.381272Z","end":"2026-05-30T09:43:49.616465Z","steps":["trace[784394314] 'agreement among raft nodes before linearized reading' (duration: 234.104887ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:49.792238Z","caller":"traceutil/trace.go:171","msg":"trace[1213362520] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"170.075202ms","start":"2026-05-30T09:43:49.622123Z","end":"2026-05-30T09:43:49.792198Z","steps":["trace[1213362520] 'process raft request' (duration: 113.02581ms)","trace[1213362520] 'compare' (duration: 56.877798ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:50.001337Z","caller":"traceutil/trace.go:171","msg":"trace[1516444821] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"114.181637ms","start":"2026-05-30T09:43:49.887126Z","end":"2026-05-30T09:43:50.001307Z","steps":["trace[1516444821] 'process raft request' (duration: 65.340375ms)","trace[1516444821] 'compare' (duration: 48.659328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:04.148666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.765909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:44:04.148798Z","caller":"traceutil/trace.go:171","msg":"trace[475325432] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1167; }","duration":"102.969344ms","start":"2026-05-30T09:44:04.045799Z","end":"2026-05-30T09:44:04.148769Z","steps":["trace[475325432] 'count revisions from in-memory index tree' (duration: 102.630577ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:05.8986Z","caller":"traceutil/trace.go:171","msg":"trace[2028687429] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"146.198338ms","start":"2026-05-30T09:44:05.752377Z","end":"2026-05-30T09:44:05.898576Z","steps":["trace[2028687429] 'read index received' (duration: 145.960533ms)","trace[2028687429] 'applied index is now lower than readState.Index' (duration: 236.775µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:05.898729Z","caller":"traceutil/trace.go:171","msg":"trace[1280435792] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"187.471827ms","start":"2026-05-30T09:44:05.711241Z","end":"2026-05-30T09:44:05.898713Z","steps":["trace[1280435792] 'process raft request' (duration: 187.17511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:05.899123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.73368ms","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-30T09:44:05.899184Z","caller":"traceutil/trace.go:171","msg":"trace[516438011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1175; }","duration":"146.835131ms","start":"2026-05-30T09:44:05.752334Z","end":"2026-05-30T09:44:05.89917Z","steps":["trace[516438011] 'agreement among raft nodes before linearized reading' (duration: 146.695079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:06.661807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279161588514195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:44:07.162744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279161588514195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:44:07.21725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29346915s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:44:07.303964Z","caller":"traceutil/trace.go:171","msg":"trace[213401531] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"1.399649671s","start":"2026-05-30T09:44:05.90426Z","end":"2026-05-30T09:44:07.30391Z","steps":["trace[213401531] 'process raft request' (duration: 1.313281988s)","trace[213401531] 'compare' (duration: 86.055237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:07.304204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:05.904241Z","time spent":"1.399834974s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:44:07.306934Z","caller":"traceutil/trace.go:171","msg":"trace[809257250] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.357292918s","start":"2026-05-30T09:44:05.949607Z","end":"2026-05-30T09:44:07.3069Z","steps":["trace[809257250] 'process raft request' (duration: 1.35688886s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:07.307157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:05.949577Z","time spent":"1.357454982s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:44:07.307801Z","caller":"traceutil/trace.go:171","msg":"trace[1817719322] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1211; }","duration":"1.147079556s","start":"2026-05-30T09:44:06.160698Z","end":"2026-05-30T09:44:07.307778Z","steps":["trace[1817719322] 'read index received' (duration: 1.056804863s)","trace[1817719322] 'applied index is now lower than readState.Index' (duration: 90.273153ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:07.307936Z","caller":"traceutil/trace.go:171","msg":"trace[1509388707] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"727.921011ms","start":"2026-05-30T09:44:06.579983Z","end":"2026-05-30T09:44:07.307904Z","steps":["trace[1509388707] 'process raft request' (duration: 727.704417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:07.308085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:06.579956Z","time spent":"728.052823ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:44:07.308117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147415592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:44:07.309986Z","caller":"traceutil/trace.go:171","msg":"trace[139412883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"1.149332791s","start":"2026-05-30T09:44:06.16064Z","end":"2026-05-30T09:44:07.309972Z","steps":["trace[139412883] 'agreement among raft nodes before linearized reading' (duration: 1.147373432s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:07.310084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:06.160604Z","time spent":"1.149468044s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:44:07.308124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.284622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:07.310336Z","caller":"traceutil/trace.go:171","msg":"trace[539816517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"393.589479ms","start":"2026-05-30T09:44:06.916733Z","end":"2026-05-30T09:44:07.310323Z","steps":["trace[539816517] 'agreement among raft nodes before linearized reading' (duration: 391.286253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:07.310362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:06.916702Z","time spent":"393.65236ms","remote":"127.0.0.1:52106","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-30T09:44:07.308395Z","caller":"traceutil/trace.go:171","msg":"trace[1781852774] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"1.200795033s","start":"2026-05-30T09:44:06.106985Z","end":"2026-05-30T09:44:07.30778Z","steps":["trace[1781852774] 'process raft request' (duration: 1.199663159s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:07.310654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:06.106928Z","time spent":"1.203675621s","remote":"127.0.0.1:52276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6384,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:44:21.012978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.768747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:44:21.013144Z","caller":"traceutil/trace.go:171","msg":"trace[648999172] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"274.040693ms","start":"2026-05-30T09:44:20.739082Z","end":"2026-05-30T09:44:21.013122Z","steps":["trace[648999172] 'process raft request' (duration: 172.057872ms)","trace[648999172] 'compare' (duration: 101.623425ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:21.215547Z","caller":"traceutil/trace.go:171","msg":"trace[145197969] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"299.709349ms","start":"2026-05-30T09:44:20.915806Z","end":"2026-05-30T09:44:21.215516Z","steps":["trace[145197969] 'read index received' (duration: 208.904355ms)","trace[145197969] 'applied index is now lower than readState.Index' (duration: 90.804074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:21.215696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.888432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:21.215645Z","caller":"traceutil/trace.go:171","msg":"trace[1596041083] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"454.467866ms","start":"2026-05-30T09:44:20.761136Z","end":"2026-05-30T09:44:21.215603Z","steps":["trace[1596041083] 'process raft request' (duration: 363.663763ms)","trace[1596041083] 'compare' (duration: 90.532008ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:21.215729Z","caller":"traceutil/trace.go:171","msg":"trace[1001716161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"299.994264ms","start":"2026-05-30T09:44:20.915724Z","end":"2026-05-30T09:44:21.215718Z","steps":["trace[1001716161] 'agreement among raft nodes before linearized reading' (duration: 299.889662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:21.215881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:20.761107Z","time spent":"454.658299ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105217,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:44:21.215773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:20.915697Z","time spent":"300.068325ms","remote":"127.0.0.1:52106","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-30T09:44:21.489833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.73297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:44:21.490519Z","caller":"traceutil/trace.go:171","msg":"trace[1832617356] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"219.271637ms","start":"2026-05-30T09:44:21.271198Z","end":"2026-05-30T09:44:21.49047Z","steps":["trace[1832617356] 'process raft request' (duration: 77.779603ms)","trace[1832617356] 'compare' (duration: 140.537886ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:21.490932Z","caller":"traceutil/trace.go:171","msg":"trace[148306739] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1325; }","duration":"108.987032ms","start":"2026-05-30T09:44:21.381914Z","end":"2026-05-30T09:44:21.490901Z","steps":["trace[148306739] 'read index received' (duration: 65.320111ms)","trace[148306739] 'applied index is now lower than readState.Index' (duration: 43.665651ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:21.491039Z","caller":"traceutil/trace.go:171","msg":"trace[1696478369] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"120.027176ms","start":"2026-05-30T09:44:21.371001Z","end":"2026-05-30T09:44:21.491029Z","steps":["trace[1696478369] 'process raft request' (duration: 118.947395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:21.491312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.379239ms","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-30T09:44:21.491351Z","caller":"traceutil/trace.go:171","msg":"trace[101355032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"109.463991ms","start":"2026-05-30T09:44:21.381879Z","end":"2026-05-30T09:44:21.491343Z","steps":["trace[101355032] 'agreement among raft nodes before linearized reading' (duration: 109.259547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:21.491616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.743803ms","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-30T09:44:21.491664Z","caller":"traceutil/trace.go:171","msg":"trace[840396226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"104.821905ms","start":"2026-05-30T09:44:21.386831Z","end":"2026-05-30T09:44:21.491653Z","steps":["trace[840396226] 'agreement among raft nodes before linearized reading' (duration: 104.686473ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:21.840472Z","caller":"traceutil/trace.go:171","msg":"trace[656932155] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"219.697424ms","start":"2026-05-30T09:44:21.620751Z","end":"2026-05-30T09:44:21.840448Z","steps":["trace[656932155] 'process raft request' (duration: 219.530611ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:26.383913Z","caller":"traceutil/trace.go:171","msg":"trace[162746476] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"359.954963ms","start":"2026-05-30T09:44:26.023935Z","end":"2026-05-30T09:44:26.383889Z","steps":["trace[162746476] 'read index received' (duration: 359.728829ms)","trace[162746476] 'applied index is now lower than readState.Index' (duration: 225.264µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:26.384015Z","caller":"traceutil/trace.go:171","msg":"trace[1867796346] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"381.94606ms","start":"2026-05-30T09:44:26.002009Z","end":"2026-05-30T09:44:26.383955Z","steps":["trace[1867796346] 'process raft request' (duration: 381.739507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:26.384154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.199558ms","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-30T09:44:26.384195Z","caller":"traceutil/trace.go:171","msg":"trace[1515445846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"360.257289ms","start":"2026-05-30T09:44:26.023925Z","end":"2026-05-30T09:44:26.384183Z","steps":["trace[1515445846] 'agreement among raft nodes before linearized reading' (duration: 360.070706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:26.384228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:26.023873Z","time spent":"360.346491ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:44:26.384278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:26.001988Z","time spent":"382.177054ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:44:31.871825Z","caller":"traceutil/trace.go:171","msg":"trace[1126697909] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"256.564414ms","start":"2026-05-30T09:44:31.615232Z","end":"2026-05-30T09:44:31.871796Z","steps":["trace[1126697909] 'read index received' (duration: 256.410312ms)","trace[1126697909] 'applied index is now lower than readState.Index' (duration: 152.982µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:31.87188Z","caller":"traceutil/trace.go:171","msg":"trace[1942833218] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"342.649653ms","start":"2026-05-30T09:44:31.529193Z","end":"2026-05-30T09:44:31.871843Z","steps":["trace[1942833218] 'process raft request' (duration: 342.44763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:31.872047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:31.529164Z","time spent":"342.796575ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:44:31.872214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.951571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T09:44:31.872248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.260404ms","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-30T09:44:31.872303Z","caller":"traceutil/trace.go:171","msg":"trace[417745721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"257.092574ms","start":"2026-05-30T09:44:31.61519Z","end":"2026-05-30T09:44:31.872282Z","steps":["trace[417745721] 'agreement among raft nodes before linearized reading' (duration: 256.763048ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:31.872308Z","caller":"traceutil/trace.go:171","msg":"trace[779928506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1439; }","duration":"254.354505ms","start":"2026-05-30T09:44:31.617935Z","end":"2026-05-30T09:44:31.87229Z","steps":["trace[779928506] 'agreement among raft nodes before linearized reading' (duration: 254.117961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:31.872514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.022842ms","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-30T09:44:31.872557Z","caller":"traceutil/trace.go:171","msg":"trace[1220676912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"257.114953ms","start":"2026-05-30T09:44:31.615429Z","end":"2026-05-30T09:44:31.872544Z","steps":["trace[1220676912] 'agreement among raft nodes before linearized reading' (duration: 256.533773ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:34.679462Z","caller":"traceutil/trace.go:171","msg":"trace[624614444] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"195.871997ms","start":"2026-05-30T09:44:34.483553Z","end":"2026-05-30T09:44:34.679425Z","steps":["trace[624614444] 'process raft request' (duration: 195.642384ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:34.698033Z","caller":"traceutil/trace.go:171","msg":"trace[1487673849] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"213.383489ms","start":"2026-05-30T09:44:34.484616Z","end":"2026-05-30T09:44:34.697999Z","steps":["trace[1487673849] 'process raft request' (duration: 213.221156ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:36.439609Z","caller":"traceutil/trace.go:171","msg":"trace[1973838876] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"138.006155ms","start":"2026-05-30T09:44:36.301583Z","end":"2026-05-30T09:44:36.43959Z","steps":["trace[1973838876] 'process raft request' (duration: 137.873722ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:38.112082Z","caller":"traceutil/trace.go:171","msg":"trace[1565670571] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"225.264705ms","start":"2026-05-30T09:44:37.886797Z","end":"2026-05-30T09:44:38.112062Z","steps":["trace[1565670571] 'read index received' (duration: 225.124362ms)","trace[1565670571] 'applied index is now lower than readState.Index' (duration: 139.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:38.112223Z","caller":"traceutil/trace.go:171","msg":"trace[912249093] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"234.806786ms","start":"2026-05-30T09:44:37.877405Z","end":"2026-05-30T09:44:38.112212Z","steps":["trace[912249093] 'process raft request' (duration: 234.530322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:38.11232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.504989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:38.112368Z","caller":"traceutil/trace.go:171","msg":"trace[1351820390] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1487; }","duration":"225.592321ms","start":"2026-05-30T09:44:37.886763Z","end":"2026-05-30T09:44:38.112355Z","steps":["trace[1351820390] 'agreement among raft nodes before linearized reading' (duration: 225.503479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:38.112911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.471917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:44:38.112959Z","caller":"traceutil/trace.go:171","msg":"trace[365787277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1488; }","duration":"212.540178ms","start":"2026-05-30T09:44:37.900405Z","end":"2026-05-30T09:44:38.112945Z","steps":["trace[365787277] 'agreement among raft nodes before linearized reading' (duration: 212.388276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:38.112919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.141604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:38.112985Z","caller":"traceutil/trace.go:171","msg":"trace[1248051757] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"222.017439ms","start":"2026-05-30T09:44:37.890951Z","end":"2026-05-30T09:44:38.112969Z","steps":["trace[1248051757] 'process raft request' (duration: 221.780675ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:38.113046Z","caller":"traceutil/trace.go:171","msg":"trace[1667377119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"198.298956ms","start":"2026-05-30T09:44:37.91472Z","end":"2026-05-30T09:44:38.113019Z","steps":["trace[1667377119] 'agreement among raft nodes before linearized reading' (duration: 198.110363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:38.113135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.774124ms","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-30T09:44:38.113163Z","caller":"traceutil/trace.go:171","msg":"trace[441651269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1488; }","duration":"211.804445ms","start":"2026-05-30T09:44:37.901349Z","end":"2026-05-30T09:44:38.113154Z","steps":["trace[441651269] 'agreement among raft nodes before linearized reading' (duration: 211.718763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:38.113306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.752737ms","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-30T09:44:38.113332Z","caller":"traceutil/trace.go:171","msg":"trace[144414477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1488; }","duration":"207.778777ms","start":"2026-05-30T09:44:37.905546Z","end":"2026-05-30T09:44:38.113325Z","steps":["trace[144414477] 'agreement among raft nodes before linearized reading' (duration: 207.705936ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:39.967938Z","caller":"traceutil/trace.go:171","msg":"trace[1562344755] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"234.026342ms","start":"2026-05-30T09:44:39.733884Z","end":"2026-05-30T09:44:39.96791Z","steps":["trace[1562344755] 'process raft request' (duration: 154.860922ms)","trace[1562344755] 'compare' (duration: 78.862845ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:40.169521Z","caller":"traceutil/trace.go:171","msg":"trace[430850853] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"127.42762ms","start":"2026-05-30T09:44:40.042061Z","end":"2026-05-30T09:44:40.169488Z","steps":["trace[430850853] 'process raft request' (duration: 89.597638ms)","trace[430850853] 'compare' (duration: 37.306344ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:41.109853Z","caller":"traceutil/trace.go:171","msg":"trace[745143633] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"198.548461ms","start":"2026-05-30T09:44:40.911266Z","end":"2026-05-30T09:44:41.109815Z","steps":["trace[745143633] 'process raft request' (duration: 198.450619ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:41.110378Z","caller":"traceutil/trace.go:171","msg":"trace[933711254] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"204.177805ms","start":"2026-05-30T09:44:40.906168Z","end":"2026-05-30T09:44:41.110345Z","steps":["trace[933711254] 'process raft request' (duration: 161.895022ms)","trace[933711254] 'compare' (duration: 41.49158ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:43.992034Z","caller":"traceutil/trace.go:171","msg":"trace[1270492030] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"134.406465ms","start":"2026-05-30T09:44:43.857565Z","end":"2026-05-30T09:44:43.991972Z","steps":["trace[1270492030] 'process raft request' (duration: 134.10242ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:45.247645Z","caller":"traceutil/trace.go:171","msg":"trace[1585119171] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"119.159715ms","start":"2026-05-30T09:44:45.128448Z","end":"2026-05-30T09:44:45.247608Z","steps":["trace[1585119171] 'process raft request' (duration: 100.995298ms)","trace[1585119171] 'compare' (duration: 18.020405ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:46.026038Z","caller":"traceutil/trace.go:171","msg":"trace[563250069] linearizableReadLoop","detail":"{readStateIndex:1733; appliedIndex:1732; }","duration":"111.261464ms","start":"2026-05-30T09:44:45.914752Z","end":"2026-05-30T09:44:46.026013Z","steps":["trace[563250069] 'read index received' (duration: 111.043131ms)","trace[563250069] 'applied index is now lower than readState.Index' (duration: 216.913µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:46.026223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.455658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:46.026189Z","caller":"traceutil/trace.go:171","msg":"trace[1767700503] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"393.555493ms","start":"2026-05-30T09:44:45.632583Z","end":"2026-05-30T09:44:46.026138Z","steps":["trace[1767700503] 'process raft request' (duration: 393.223928ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:46.026258Z","caller":"traceutil/trace.go:171","msg":"trace[1435991644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1668; }","duration":"111.535609ms","start":"2026-05-30T09:44:45.914712Z","end":"2026-05-30T09:44:46.026248Z","steps":["trace[1435991644] 'agreement among raft nodes before linearized reading' (duration: 111.468318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.0264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.632552Z","time spent":"393.739886ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:44:47.326038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.316708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:44:47.326149Z","caller":"traceutil/trace.go:171","msg":"trace[630838211] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"298.383016ms","start":"2026-05-30T09:44:47.027746Z","end":"2026-05-30T09:44:47.326129Z","steps":["trace[630838211] 'process raft request' (duration: 44.879105ms)","trace[630838211] 'compare' (duration: 253.078094ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:47.326735Z","caller":"traceutil/trace.go:171","msg":"trace[409892330] linearizableReadLoop","detail":"{readStateIndex:1749; appliedIndex:1747; }","duration":"286.621615ms","start":"2026-05-30T09:44:47.040092Z","end":"2026-05-30T09:44:47.326714Z","steps":["trace[409892330] 'read index received' (duration: 32.524265ms)","trace[409892330] 'applied index is now lower than readState.Index' (duration: 254.09632ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:47.326804Z","caller":"traceutil/trace.go:171","msg":"trace[852133918] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"290.083751ms","start":"2026-05-30T09:44:47.036693Z","end":"2026-05-30T09:44:47.326776Z","steps":["trace[852133918] 'process raft request' (duration: 289.862858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:47.326902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.552441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:44:47.326892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.752767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:47.326928Z","caller":"traceutil/trace.go:171","msg":"trace[1993098269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1684; }","duration":"258.624963ms","start":"2026-05-30T09:44:47.068296Z","end":"2026-05-30T09:44:47.326921Z","steps":["trace[1993098269] 'agreement among raft nodes before linearized reading' (duration: 258.522451ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:47.326958Z","caller":"traceutil/trace.go:171","msg":"trace[997817899] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1684; }","duration":"286.866979ms","start":"2026-05-30T09:44:47.040075Z","end":"2026-05-30T09:44:47.326942Z","steps":["trace[997817899] 'agreement among raft nodes before linearized reading' (duration: 286.727887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:47.327001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.069314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:47.327064Z","caller":"traceutil/trace.go:171","msg":"trace[1273963420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1684; }","duration":"190.239457ms","start":"2026-05-30T09:44:47.136809Z","end":"2026-05-30T09:44:47.327048Z","steps":["trace[1273963420] 'agreement among raft nodes before linearized reading' (duration: 190.071484ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:47.795649Z","caller":"traceutil/trace.go:171","msg":"trace[1875058261] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"223.493605ms","start":"2026-05-30T09:44:47.572142Z","end":"2026-05-30T09:44:47.795636Z","steps":["trace[1875058261] 'process raft request' (duration: 223.349232ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:47.795611Z","caller":"traceutil/trace.go:171","msg":"trace[121140100] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"224.848157ms","start":"2026-05-30T09:44:47.570732Z","end":"2026-05-30T09:44:47.79558Z","steps":["trace[121140100] 'process raft request' (duration: 138.222656ms)","trace[121140100] 'compare' (duration: 86.268815ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:48.201594Z","caller":"traceutil/trace.go:171","msg":"trace[1344953838] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"178.21603ms","start":"2026-05-30T09:44:48.021141Z","end":"2026-05-30T09:44:48.199357Z","steps":["trace[1344953838] 'process raft request' (duration: 177.702741ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:53.615622Z","caller":"traceutil/trace.go:171","msg":"trace[543660718] linearizableReadLoop","detail":"{readStateIndex:1828; appliedIndex:1827; }","duration":"191.353394ms","start":"2026-05-30T09:44:53.424243Z","end":"2026-05-30T09:44:53.615597Z","steps":["trace[543660718] 'read index received' (duration: 174.455478ms)","trace[543660718] 'applied index is now lower than readState.Index' (duration: 16.896486ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:53.615761Z","caller":"traceutil/trace.go:171","msg":"trace[1455941534] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"208.712007ms","start":"2026-05-30T09:44:53.407015Z","end":"2026-05-30T09:44:53.615727Z","steps":["trace[1455941534] 'process raft request' (duration: 192.270988ms)","trace[1455941534] 'compare' (duration: 15.688288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:53.615961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.702219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:44:53.615995Z","caller":"traceutil/trace.go:171","msg":"trace[731803929] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1757; }","duration":"191.79481ms","start":"2026-05-30T09:44:53.424191Z","end":"2026-05-30T09:44:53.615986Z","steps":["trace[731803929] 'agreement among raft nodes before linearized reading' (duration: 191.716179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:53.618574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.222659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:53.618627Z","caller":"traceutil/trace.go:171","msg":"trace[1726751149] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1759; }","duration":"159.31926ms","start":"2026-05-30T09:44:53.459293Z","end":"2026-05-30T09:44:53.618612Z","steps":["trace[1726751149] 'agreement among raft nodes before linearized reading' (duration: 159.209248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.5947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.287563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:45:01.594811Z","caller":"traceutil/trace.go:171","msg":"trace[1868720973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1864; }","duration":"547.456566ms","start":"2026-05-30T09:45:01.04733Z","end":"2026-05-30T09:45:01.594787Z","steps":["trace[1868720973] 'count revisions from in-memory index tree' (duration: 547.217542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.594917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:01.047313Z","time spent":"547.582258ms","remote":"127.0.0.1:38766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:45:01.595236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.860643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-30T09:45:01.595294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.585278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-30T09:45:01.595362Z","caller":"traceutil/trace.go:171","msg":"trace[198920959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1864; }","duration":"671.045695ms","start":"2026-05-30T09:45:00.924284Z","end":"2026-05-30T09:45:01.595329Z","steps":["trace[198920959] 'range keys from in-memory index tree' (duration: 670.564427ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:01.595403Z","caller":"traceutil/trace.go:171","msg":"trace[144489646] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1; range_end:; response_count:1; response_revision:1864; }","duration":"482.71671ms","start":"2026-05-30T09:45:01.112662Z","end":"2026-05-30T09:45:01.595379Z","steps":["trace[144489646] 'range keys from in-memory index tree' (duration: 482.325424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.595463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:01.112583Z","time spent":"482.869662ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1\" "} {"level":"warn","ts":"2026-05-30T09:45:01.595414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:00.924256Z","time spent":"671.147227ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:45:01.594925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.797838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:45:01.59643Z","caller":"traceutil/trace.go:171","msg":"trace[1708679156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"681.353093ms","start":"2026-05-30T09:45:00.915003Z","end":"2026-05-30T09:45:01.596356Z","steps":["trace[1708679156] 'range keys from in-memory index tree' (duration: 679.681147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.596934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:00.914979Z","time spent":"681.84507ms","remote":"127.0.0.1:52106","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-30T09:45:01.610169Z","caller":"traceutil/trace.go:171","msg":"trace[798402812] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"397.48339ms","start":"2026-05-30T09:45:01.212653Z","end":"2026-05-30T09:45:01.610137Z","steps":["trace[798402812] 'read index received' (duration: 397.327258ms)","trace[798402812] 'applied index is now lower than readState.Index' (duration: 154.872µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:45:01.610247Z","caller":"traceutil/trace.go:171","msg":"trace[1242670377] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"486.309684ms","start":"2026-05-30T09:45:01.123908Z","end":"2026-05-30T09:45:01.610218Z","steps":["trace[1242670377] 'process raft request' (duration: 486.05149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.610435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.761794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-30T09:45:01.610474Z","caller":"traceutil/trace.go:171","msg":"trace[1174335904] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1; range_end:; response_count:1; response_revision:1865; }","duration":"397.885366ms","start":"2026-05-30T09:45:01.212578Z","end":"2026-05-30T09:45:01.610464Z","steps":["trace[1174335904] 'agreement among raft nodes before linearized reading' (duration: 397.694083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.610483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:01.123881Z","time spent":"486.431066ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:45:01.610511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:01.212546Z","time spent":"397.953648ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60283f71-324a-4201-8a9b-1e679922d6d1\" "} {"level":"warn","ts":"2026-05-30T09:45:01.676619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.31094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:45:01.676733Z","caller":"traceutil/trace.go:171","msg":"trace[1932302628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1867; }","duration":"285.511252ms","start":"2026-05-30T09:45:01.3912Z","end":"2026-05-30T09:45:01.676711Z","steps":["trace[1932302628] 'agreement among raft nodes before linearized reading' (duration: 285.137817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.676628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.471958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:45:01.676878Z","caller":"traceutil/trace.go:171","msg":"trace[2096538979] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1867; }","duration":"164.735412ms","start":"2026-05-30T09:45:01.512084Z","end":"2026-05-30T09:45:01.67682Z","steps":["trace[2096538979] 'agreement among raft nodes before linearized reading' (duration: 164.373936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:01.67685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.130548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-30T09:45:01.676981Z","caller":"traceutil/trace.go:171","msg":"trace[779292575] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"205.355471ms","start":"2026-05-30T09:45:01.471595Z","end":"2026-05-30T09:45:01.67695Z","steps":["trace[779292575] 'agreement among raft nodes before linearized reading' (duration: 204.921774ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:01.802786Z","caller":"traceutil/trace.go:171","msg":"trace[155682962] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"156.393665ms","start":"2026-05-30T09:45:01.646362Z","end":"2026-05-30T09:45:01.802755Z","steps":["trace[155682962] 'process raft request' (duration: 156.186852ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:01.814144Z","caller":"traceutil/trace.go:171","msg":"trace[327264995] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"131.437354ms","start":"2026-05-30T09:45:01.682684Z","end":"2026-05-30T09:45:01.814121Z","steps":["trace[327264995] 'process raft request' (duration: 131.321713ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:02.468355Z","caller":"traceutil/trace.go:171","msg":"trace[1660287669] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"165.583634ms","start":"2026-05-30T09:45:02.302723Z","end":"2026-05-30T09:45:02.468306Z","steps":["trace[1660287669] 'process raft request' (duration: 165.31861ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:10.713561Z","caller":"traceutil/trace.go:171","msg":"trace[423974741] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"147.302883ms","start":"2026-05-30T09:45:10.56623Z","end":"2026-05-30T09:45:10.713532Z","steps":["trace[423974741] 'read index received' (duration: 76.890274ms)","trace[423974741] 'applied index is now lower than readState.Index' (duration: 70.411419ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:45:10.713704Z","caller":"traceutil/trace.go:171","msg":"trace[1340549512] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"151.615587ms","start":"2026-05-30T09:45:10.562065Z","end":"2026-05-30T09:45:10.713681Z","steps":["trace[1340549512] 'process raft request' (duration: 81.068016ms)","trace[1340549512] 'compare' (duration: 70.268646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:45:10.714005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.740789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:45:10.714112Z","caller":"traceutil/trace.go:171","msg":"trace[1443535932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1955; }","duration":"147.880911ms","start":"2026-05-30T09:45:10.56621Z","end":"2026-05-30T09:45:10.714091Z","steps":["trace[1443535932] 'agreement among raft nodes before linearized reading' (duration: 147.580537ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:10.718837Z","caller":"traceutil/trace.go:171","msg":"trace[471839243] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"151.402253ms","start":"2026-05-30T09:45:10.567422Z","end":"2026-05-30T09:45:10.718824Z","steps":["trace[471839243] 'process raft request' (duration: 151.267731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:10.718884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.073529ms","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-30T09:45:10.719014Z","caller":"traceutil/trace.go:171","msg":"trace[1160727758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1956; }","duration":"147.233521ms","start":"2026-05-30T09:45:10.571768Z","end":"2026-05-30T09:45:10.719002Z","steps":["trace[1160727758] 'agreement among raft nodes before linearized reading' (duration: 147.015448ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:15.998879Z","caller":"traceutil/trace.go:171","msg":"trace[47593578] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"375.726329ms","start":"2026-05-30T09:45:15.623122Z","end":"2026-05-30T09:45:15.998848Z","steps":["trace[47593578] 'process raft request' (duration: 375.600647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:15.998992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:15.623092Z","time spent":"375.83897ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5881,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:45:15.999137Z","caller":"traceutil/trace.go:171","msg":"trace[1809922296] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"252.889797ms","start":"2026-05-30T09:45:15.745958Z","end":"2026-05-30T09:45:15.998848Z","steps":["trace[1809922296] 'read index received' (duration: 252.863976ms)","trace[1809922296] 'applied index is now lower than readState.Index' (duration: 21.671µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:45:15.99933Z","caller":"traceutil/trace.go:171","msg":"trace[359804163] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"141.740674ms","start":"2026-05-30T09:45:15.857578Z","end":"2026-05-30T09:45:15.999319Z","steps":["trace[359804163] 'process raft request' (duration: 141.689914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:15.999445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.474616ms","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-30T09:45:16.000725Z","caller":"traceutil/trace.go:171","msg":"trace[885609953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1988; }","duration":"254.616213ms","start":"2026-05-30T09:45:15.745874Z","end":"2026-05-30T09:45:16.00049Z","steps":["trace[885609953] 'agreement among raft nodes before linearized reading' (duration: 253.315453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:16.000808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.725458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:45:16.000917Z","caller":"traceutil/trace.go:171","msg":"trace[1968578735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"104.880791ms","start":"2026-05-30T09:45:15.89601Z","end":"2026-05-30T09:45:16.00089Z","steps":["trace[1968578735] 'agreement among raft nodes before linearized reading' (duration: 104.594917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:16.001224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.616756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-30T09:45:16.004313Z","caller":"traceutil/trace.go:171","msg":"trace[448013621] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1989; }","duration":"220.769411ms","start":"2026-05-30T09:45:15.783499Z","end":"2026-05-30T09:45:16.004269Z","steps":["trace[448013621] 'agreement among raft nodes before linearized reading' (duration: 216.821705ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:35.689628Z","caller":"traceutil/trace.go:171","msg":"trace[1410221870] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"115.4232ms","start":"2026-05-30T09:45:35.574186Z","end":"2026-05-30T09:45:35.68961Z","steps":["trace[1410221870] 'read index received' (duration: 115.322948ms)","trace[1410221870] 'applied index is now lower than readState.Index' (duration: 99.612µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:45:35.689802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.600882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:45:35.689855Z","caller":"traceutil/trace.go:171","msg":"trace[2137089232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2109; }","duration":"115.716154ms","start":"2026-05-30T09:45:35.574126Z","end":"2026-05-30T09:45:35.689842Z","steps":["trace[2137089232] 'agreement among raft nodes before linearized reading' (duration: 115.583342ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:35.689759Z","caller":"traceutil/trace.go:171","msg":"trace[1729312043] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"133.998094ms","start":"2026-05-30T09:45:35.555721Z","end":"2026-05-30T09:45:35.689719Z","steps":["trace[1729312043] 'process raft request' (duration: 133.759781ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:36.211783Z","caller":"traceutil/trace.go:171","msg":"trace[1716399310] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"124.5252ms","start":"2026-05-30T09:45:36.08718Z","end":"2026-05-30T09:45:36.211705Z","steps":["trace[1716399310] 'process raft request' (duration: 124.158404ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:36.335493Z","caller":"traceutil/trace.go:171","msg":"trace[1260706949] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"118.858213ms","start":"2026-05-30T09:45:36.216606Z","end":"2026-05-30T09:45:36.335465Z","steps":["trace[1260706949] 'process raft request' (duration: 118.789072ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:36.335642Z","caller":"traceutil/trace.go:171","msg":"trace[248865237] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"119.290989ms","start":"2026-05-30T09:45:36.216291Z","end":"2026-05-30T09:45:36.335582Z","steps":["trace[248865237] 'process raft request' (duration: 106.19839ms)","trace[248865237] 'compare' (duration: 12.766495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:45:37.107799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.566392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:45:37.108579Z","caller":"traceutil/trace.go:171","msg":"trace[1847829112] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"442.534797ms","start":"2026-05-30T09:45:36.665963Z","end":"2026-05-30T09:45:37.108498Z","steps":["trace[1847829112] 'process raft request' (duration: 277.098322ms)","trace[1847829112] 'compare' (duration: 164.35667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:45:37.108777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:45:36.665929Z","time spent":"442.74142ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:45:37.109302Z","caller":"traceutil/trace.go:171","msg":"trace[26046528] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"282.997509ms","start":"2026-05-30T09:45:36.826282Z","end":"2026-05-30T09:45:37.10928Z","steps":["trace[26046528] 'process raft request' (duration: 282.716646ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:37.109513Z","caller":"traceutil/trace.go:171","msg":"trace[158216257] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2193; }","duration":"214.24864ms","start":"2026-05-30T09:45:36.894959Z","end":"2026-05-30T09:45:37.109208Z","steps":["trace[158216257] 'read index received' (duration: 48.175007ms)","trace[158216257] 'applied index is now lower than readState.Index' (duration: 166.072193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:45:37.10959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.627336ms","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-30T09:45:37.109735Z","caller":"traceutil/trace.go:171","msg":"trace[15196919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2115; }","duration":"214.88452ms","start":"2026-05-30T09:45:36.894835Z","end":"2026-05-30T09:45:37.10972Z","steps":["trace[15196919] 'agreement among raft nodes before linearized reading' (duration: 214.601606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:37.110049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.309925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:45:37.110077Z","caller":"traceutil/trace.go:171","msg":"trace[909095106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2115; }","duration":"193.340486ms","start":"2026-05-30T09:45:36.91673Z","end":"2026-05-30T09:45:37.11007Z","steps":["trace[909095106] 'agreement among raft nodes before linearized reading' (duration: 193.259245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:37.110301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.247926ms","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-30T09:45:37.110442Z","caller":"traceutil/trace.go:171","msg":"trace[1105107211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2115; }","duration":"177.482628ms","start":"2026-05-30T09:45:36.932935Z","end":"2026-05-30T09:45:37.110418Z","steps":["trace[1105107211] 'agreement among raft nodes before linearized reading' (duration: 177.026862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:37.110618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.305888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:45:37.1109Z","caller":"traceutil/trace.go:171","msg":"trace[73032439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"194.978809ms","start":"2026-05-30T09:45:36.915908Z","end":"2026-05-30T09:45:37.110887Z","steps":["trace[73032439] 'agreement among raft nodes before linearized reading' (duration: 194.012985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:37.347575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.133357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:45:37.347932Z","caller":"traceutil/trace.go:171","msg":"trace[68741005] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2195; }","duration":"128.994676ms","start":"2026-05-30T09:45:37.218911Z","end":"2026-05-30T09:45:37.347905Z","steps":["trace[68741005] 'read index received' (duration: 61.147293ms)","trace[68741005] 'applied index is now lower than readState.Index' (duration: 67.844893ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:45:37.348033Z","caller":"traceutil/trace.go:171","msg":"trace[618741144] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"232.493236ms","start":"2026-05-30T09:45:37.115503Z","end":"2026-05-30T09:45:37.347996Z","steps":["trace[618741144] 'process raft request' (duration: 232.292463ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:37.347997Z","caller":"traceutil/trace.go:171","msg":"trace[1123033878] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"233.793253ms","start":"2026-05-30T09:45:37.114056Z","end":"2026-05-30T09:45:37.347849Z","steps":["trace[1123033878] 'process raft request' (duration: 96.22964ms)","trace[1123033878] 'compare' (duration: 136.938734ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:45:37.348034Z","caller":"traceutil/trace.go:171","msg":"trace[747035354] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"233.742472ms","start":"2026-05-30T09:45:37.114195Z","end":"2026-05-30T09:45:37.347938Z","steps":["trace[747035354] 'process raft request' (duration: 233.542499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:45:37.349253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.312265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-30T09:45:37.349361Z","caller":"traceutil/trace.go:171","msg":"trace[1634693345] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2118; }","duration":"130.494157ms","start":"2026-05-30T09:45:37.218839Z","end":"2026-05-30T09:45:37.349333Z","steps":["trace[1634693345] 'agreement among raft nodes before linearized reading' (duration: 129.140968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:02.019216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279161588516494,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:46:02.081845Z","caller":"traceutil/trace.go:171","msg":"trace[1474262748] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"612.308645ms","start":"2026-05-30T09:46:01.469504Z","end":"2026-05-30T09:46:02.081813Z","steps":["trace[1474262748] 'process raft request' (duration: 612.123971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:02.082015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:01.469489Z","time spent":"612.432696ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:46:02.082406Z","caller":"traceutil/trace.go:171","msg":"trace[103645960] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2329; }","duration":"563.645134ms","start":"2026-05-30T09:46:01.518743Z","end":"2026-05-30T09:46:02.082388Z","steps":["trace[103645960] 'read index received' (duration: 562.860443ms)","trace[103645960] 'applied index is now lower than readState.Index' (duration: 783.971µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:46:02.082516Z","caller":"traceutil/trace.go:171","msg":"trace[242632324] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"401.10485ms","start":"2026-05-30T09:46:01.681393Z","end":"2026-05-30T09:46:02.082498Z","steps":["trace[242632324] 'process raft request' (duration: 400.964638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:02.0826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.845957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-30T09:46:02.082617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:01.681368Z","time spent":"401.199751ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:46:02.082658Z","caller":"traceutil/trace.go:171","msg":"trace[646687177] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2247; }","duration":"563.937589ms","start":"2026-05-30T09:46:01.51871Z","end":"2026-05-30T09:46:02.082648Z","steps":["trace[646687177] 'agreement among raft nodes before linearized reading' (duration: 563.750787ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:46:02.082673Z","caller":"traceutil/trace.go:171","msg":"trace[1891001089] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"612.650399ms","start":"2026-05-30T09:46:01.469998Z","end":"2026-05-30T09:46:02.082648Z","steps":["trace[1891001089] 'process raft request' (duration: 612.217174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:02.082688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:01.5187Z","time spent":"563.98114ms","remote":"127.0.0.1:37274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-30T09:46:02.082558Z","caller":"traceutil/trace.go:171","msg":"trace[2124862528] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"612.039491ms","start":"2026-05-30T09:46:01.470467Z","end":"2026-05-30T09:46:02.082507Z","steps":["trace[2124862528] 'process raft request' (duration: 611.839769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:46:02.082839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:01.469973Z","time spent":"612.77383ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:46:02.082953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:46:01.47046Z","time spent":"612.432516ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:46:32.19869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.766978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:46:32.198819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.223273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:46:32.198836Z","caller":"traceutil/trace.go:171","msg":"trace[1583386216] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2456; }","duration":"268.94306ms","start":"2026-05-30T09:46:31.92986Z","end":"2026-05-30T09:46:32.198803Z","steps":["trace[1583386216] 'count revisions from in-memory index tree' (duration: 268.655607ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:46:32.198909Z","caller":"traceutil/trace.go:171","msg":"trace[1463181024] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2456; }","duration":"252.351195ms","start":"2026-05-30T09:46:31.946541Z","end":"2026-05-30T09:46:32.198892Z","steps":["trace[1463181024] 'range keys from in-memory index tree' (duration: 252.167113ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:46:41.556923Z","caller":"traceutil/trace.go:171","msg":"trace[385265736] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"103.176495ms","start":"2026-05-30T09:46:41.453728Z","end":"2026-05-30T09:46:41.556905Z","steps":["trace[385265736] 'process raft request' (duration: 103.062634ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:46:41.565156Z","caller":"traceutil/trace.go:171","msg":"trace[64898373] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"108.909385ms","start":"2026-05-30T09:46:41.45624Z","end":"2026-05-30T09:46:41.565149Z","steps":["trace[64898373] 'process raft request' (duration: 107.895433ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:13.787615Z","caller":"traceutil/trace.go:171","msg":"trace[1566441857] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"191.912868ms","start":"2026-05-30T09:47:13.595675Z","end":"2026-05-30T09:47:13.787587Z","steps":["trace[1566441857] 'process raft request' (duration: 191.745962ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:13.863772Z","caller":"traceutil/trace.go:171","msg":"trace[47875638] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"169.711967ms","start":"2026-05-30T09:47:13.694033Z","end":"2026-05-30T09:47:13.863745Z","steps":["trace[47875638] 'process raft request' (duration: 154.299358ms)","trace[47875638] 'compare' (duration: 15.193241ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:13.863984Z","caller":"traceutil/trace.go:171","msg":"trace[1454819698] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"163.433961ms","start":"2026-05-30T09:47:13.700528Z","end":"2026-05-30T09:47:13.863962Z","steps":["trace[1454819698] 'process raft request' (duration: 163.13631ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:34.714869Z","caller":"traceutil/trace.go:171","msg":"trace[1840320770] linearizableReadLoop","detail":"{readStateIndex:2960; appliedIndex:2958; }","duration":"115.918051ms","start":"2026-05-30T09:47:34.598919Z","end":"2026-05-30T09:47:34.714837Z","steps":["trace[1840320770] 'read index received' (duration: 95.516427ms)","trace[1840320770] 'applied index is now lower than readState.Index' (duration: 20.400794ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:34.714965Z","caller":"traceutil/trace.go:171","msg":"trace[1387848728] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"116.512801ms","start":"2026-05-30T09:47:34.598409Z","end":"2026-05-30T09:47:34.714922Z","steps":["trace[1387848728] 'process raft request' (duration: 116.316164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:34.715275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.338246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-30T09:47:34.715451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.629392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b44ed18ae4f0d1\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-30T09:47:34.715514Z","caller":"traceutil/trace.go:171","msg":"trace[786308666] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b44ed18ae4f0d1; range_end:; response_count:1; response_revision:2853; }","duration":"110.753176ms","start":"2026-05-30T09:47:34.604746Z","end":"2026-05-30T09:47:34.7155Z","steps":["trace[786308666] 'agreement among raft nodes before linearized reading' (duration: 110.502777ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:34.715324Z","caller":"traceutil/trace.go:171","msg":"trace[278803119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2853; }","duration":"116.46054ms","start":"2026-05-30T09:47:34.598851Z","end":"2026-05-30T09:47:34.715312Z","steps":["trace[278803119] 'agreement among raft nodes before linearized reading' (duration: 116.103978ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:23.745695Z","caller":"traceutil/trace.go:171","msg":"trace[1651411431] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"278.522955ms","start":"2026-05-30T09:50:23.467138Z","end":"2026-05-30T09:50:23.745661Z","steps":["trace[1651411431] 'process raft request' (duration: 278.317941ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:23.748901Z","caller":"traceutil/trace.go:171","msg":"trace[41180540] linearizableReadLoop","detail":"{readStateIndex:4051; appliedIndex:4049; }","duration":"124.305884ms","start":"2026-05-30T09:50:23.624559Z","end":"2026-05-30T09:50:23.748864Z","steps":["trace[41180540] 'read index received' (duration: 120.910608ms)","trace[41180540] 'applied index is now lower than readState.Index' (duration: 3.394006ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:23.749047Z","caller":"traceutil/trace.go:171","msg":"trace[757542507] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"281.697418ms","start":"2026-05-30T09:50:23.46728Z","end":"2026-05-30T09:50:23.748977Z","steps":["trace[757542507] 'process raft request' (duration: 281.415212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:23.749766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.173352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:50:23.749843Z","caller":"traceutil/trace.go:171","msg":"trace[476943437] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3901; }","duration":"125.321875ms","start":"2026-05-30T09:50:23.624499Z","end":"2026-05-30T09:50:23.749821Z","steps":["trace[476943437] 'agreement among raft nodes before linearized reading' (duration: 124.487498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:23.749811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.193432ms","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-30T09:50:23.749924Z","caller":"traceutil/trace.go:171","msg":"trace[1166362672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3901; }","duration":"104.313665ms","start":"2026-05-30T09:50:23.645594Z","end":"2026-05-30T09:50:23.749908Z","steps":["trace[1166362672] 'agreement among raft nodes before linearized reading' (duration: 103.507528ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.158385Z","caller":"traceutil/trace.go:171","msg":"trace[1410966140] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"122.676449ms","start":"2026-05-30T09:50:58.035687Z","end":"2026-05-30T09:50:58.158363Z","steps":["trace[1410966140] 'process raft request' (duration: 122.591927ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.158428Z","caller":"traceutil/trace.go:171","msg":"trace[578488922] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"123.549225ms","start":"2026-05-30T09:50:58.034831Z","end":"2026-05-30T09:50:58.158381Z","steps":["trace[578488922] 'process raft request' (duration: 57.656467ms)","trace[578488922] 'compare' (duration: 65.639903ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:58.158559Z","caller":"traceutil/trace.go:171","msg":"trace[245280987] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"121.589489ms","start":"2026-05-30T09:50:58.036945Z","end":"2026-05-30T09:50:58.158534Z","steps":["trace[245280987] 'process raft request' (duration: 121.365985ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.159125Z","caller":"traceutil/trace.go:171","msg":"trace[1502227286] transaction","detail":"{read_only:false; response_revision:4404; number_of_response:1; }","duration":"112.668047ms","start":"2026-05-30T09:50:58.046429Z","end":"2026-05-30T09:50:58.159097Z","steps":["trace[1502227286] 'process raft request' (duration: 112.064915ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.159831Z","caller":"traceutil/trace.go:171","msg":"trace[1024572855] transaction","detail":"{read_only:false; response_revision:4405; number_of_response:1; }","duration":"110.524637ms","start":"2026-05-30T09:50:58.049292Z","end":"2026-05-30T09:50:58.159816Z","steps":["trace[1024572855] 'process raft request' (duration: 109.786723ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:05.033171Z","caller":"traceutil/trace.go:171","msg":"trace[300594504] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4780; }","duration":"120.644019ms","start":"2026-05-30T09:51:04.912486Z","end":"2026-05-30T09:51:05.03313Z","steps":["trace[300594504] 'read index received' (duration: 40.434225ms)","trace[300594504] 'applied index is now lower than readState.Index' (duration: 80.207874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:05.033575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.067368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:05.033664Z","caller":"traceutil/trace.go:171","msg":"trace[2011837590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4605; }","duration":"121.164369ms","start":"2026-05-30T09:51:04.91248Z","end":"2026-05-30T09:51:05.033644Z","steps":["trace[2011837590] 'agreement among raft nodes before linearized reading' (duration: 121.028697ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:05.033881Z","caller":"traceutil/trace.go:171","msg":"trace[652311429] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"129.47134ms","start":"2026-05-30T09:51:04.904395Z","end":"2026-05-30T09:51:05.033866Z","steps":["trace[652311429] 'process raft request' (duration: 58.077175ms)","trace[652311429] 'compare' (duration: 70.323475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:05.034966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.57286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-05-30T09:51:05.035046Z","caller":"traceutil/trace.go:171","msg":"trace[1575085010] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4605; }","duration":"113.705353ms","start":"2026-05-30T09:51:04.921323Z","end":"2026-05-30T09:51:05.035028Z","steps":["trace[1575085010] 'agreement among raft nodes before linearized reading' (duration: 112.313148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:12.2582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.023606ms","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-30T09:51:12.258263Z","caller":"traceutil/trace.go:171","msg":"trace[57692006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4775; }","duration":"190.127138ms","start":"2026-05-30T09:51:12.068124Z","end":"2026-05-30T09:51:12.258252Z","steps":["trace[57692006] 'range keys from in-memory index tree' (duration: 189.883344ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:12.337886Z","caller":"traceutil/trace.go:171","msg":"trace[1518222711] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"266.239422ms","start":"2026-05-30T09:51:12.071623Z","end":"2026-05-30T09:51:12.337862Z","steps":["trace[1518222711] 'process raft request' (duration: 266.11713ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:12.337917Z","caller":"traceutil/trace.go:171","msg":"trace[896886745] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4958; }","duration":"259.915089ms","start":"2026-05-30T09:51:12.07797Z","end":"2026-05-30T09:51:12.337885Z","steps":["trace[896886745] 'read index received' (duration: 259.898588ms)","trace[896886745] 'applied index is now lower than readState.Index' (duration: 13.751µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:12.33823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.236414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7d8999965f-zgmlp\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-05-30T09:51:12.338278Z","caller":"traceutil/trace.go:171","msg":"trace[584270774] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7d8999965f-zgmlp; range_end:; response_count:1; response_revision:4776; }","duration":"260.331256ms","start":"2026-05-30T09:51:12.077932Z","end":"2026-05-30T09:51:12.338264Z","steps":["trace[584270774] 'agreement among raft nodes before linearized reading' (duration: 260.103462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:12.83829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279161588522708,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:51:13.058968Z","caller":"traceutil/trace.go:171","msg":"trace[1057248672] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"796.327672ms","start":"2026-05-30T09:51:12.262619Z","end":"2026-05-30T09:51:13.058947Z","steps":["trace[1057248672] 'process raft request' (duration: 796.20654ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:13.059115Z","caller":"traceutil/trace.go:171","msg":"trace[1680940908] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"899.691704ms","start":"2026-05-30T09:51:12.15939Z","end":"2026-05-30T09:51:13.059082Z","steps":["trace[1680940908] 'process raft request' (duration: 876.693073ms)","trace[1680940908] 'compare' (duration: 22.228218ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:13.05918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.262604Z","time spent":"796.440483ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:13.059237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.159364Z","time spent":"899.815317ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:51:13.144884Z","caller":"traceutil/trace.go:171","msg":"trace[1703396330] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"803.091661ms","start":"2026-05-30T09:51:12.341753Z","end":"2026-05-30T09:51:13.144844Z","steps":["trace[1703396330] 'process raft request' (duration: 802.889368ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:13.14493Z","caller":"traceutil/trace.go:171","msg":"trace[818278242] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"796.198436ms","start":"2026-05-30T09:51:12.348697Z","end":"2026-05-30T09:51:13.144895Z","steps":["trace[818278242] 'process raft request' (duration: 796.070564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.341733Z","time spent":"803.258984ms","remote":"127.0.0.1:52182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:51:13.145109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.34869Z","time spent":"796.310199ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:13.145057Z","caller":"traceutil/trace.go:171","msg":"trace[126969684] linearizableReadLoop","detail":"{readStateIndex:4962; appliedIndex:4958; }","duration":"807.032421ms","start":"2026-05-30T09:51:12.337997Z","end":"2026-05-30T09:51:13.14503Z","steps":["trace[126969684] 'read index received' (duration: 698.07732ms)","trace[126969684] 'applied index is now lower than readState.Index' (duration: 108.953741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:13.145343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.182908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-30T09:51:13.145398Z","caller":"traceutil/trace.go:171","msg":"trace[669936721] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4781; }","duration":"974.283089ms","start":"2026-05-30T09:51:12.1711Z","end":"2026-05-30T09:51:13.145384Z","steps":["trace[669936721] 'agreement among raft nodes before linearized reading' (duration: 974.006414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.255875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T09:51:13.145435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.171035Z","time spent":"974.390171ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-30T09:51:13.145525Z","caller":"traceutil/trace.go:171","msg":"trace[797847812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4781; }","duration":"908.333056ms","start":"2026-05-30T09:51:12.23717Z","end":"2026-05-30T09:51:13.145503Z","steps":["trace[797847812] 'agreement among raft nodes before linearized reading' (duration: 908.118182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.237143Z","time spent":"908.423538ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:51:13.14558Z","caller":"traceutil/trace.go:171","msg":"trace[530846598] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"165.94283ms","start":"2026-05-30T09:51:12.979522Z","end":"2026-05-30T09:51:13.145465Z","steps":["trace[530846598] 'process raft request' (duration: 165.312439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.14562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.702452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-30T09:51:13.145699Z","caller":"traceutil/trace.go:171","msg":"trace[1716255028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4781; }","duration":"841.817094ms","start":"2026-05-30T09:51:12.303869Z","end":"2026-05-30T09:51:13.145686Z","steps":["trace[1716255028] 'agreement among raft nodes before linearized reading' (duration: 841.60835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.303852Z","time spent":"841.878305ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-30T09:51:13.145798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.890754ms","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-30T09:51:13.14585Z","caller":"traceutil/trace.go:171","msg":"trace[1743900937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4781; }","duration":"915.008576ms","start":"2026-05-30T09:51:12.230829Z","end":"2026-05-30T09:51:13.145837Z","steps":["trace[1743900937] 'agreement among raft nodes before linearized reading' (duration: 914.858623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.230817Z","time spent":"915.064577ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:13.145937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.939701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:13.145976Z","caller":"traceutil/trace.go:171","msg":"trace[1920596729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4781; }","duration":"960.006552ms","start":"2026-05-30T09:51:12.185959Z","end":"2026-05-30T09:51:13.145965Z","steps":["trace[1920596729] 'agreement among raft nodes before linearized reading' (duration: 959.944691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.145932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.66289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-30T09:51:13.146007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.185941Z","time spent":"960.057154ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:51:13.146038Z","caller":"traceutil/trace.go:171","msg":"trace[1011027129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4781; }","duration":"919.821543ms","start":"2026-05-30T09:51:12.226205Z","end":"2026-05-30T09:51:13.146026Z","steps":["trace[1011027129] 'agreement among raft nodes before linearized reading' (duration: 919.610078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.146188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.226187Z","time spent":"919.988806ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:51:13.148141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.375317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-05-30T09:51:13.148219Z","caller":"traceutil/trace.go:171","msg":"trace[227362352] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4781; }","duration":"913.473708ms","start":"2026-05-30T09:51:12.234724Z","end":"2026-05-30T09:51:13.148197Z","steps":["trace[227362352] 'agreement among raft nodes before linearized reading' (duration: 911.187187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.148277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.234714Z","time spent":"913.54667ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206990,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-05-30T09:51:13.152383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.065554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-30T09:51:13.152429Z","caller":"traceutil/trace.go:171","msg":"trace[487003801] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:4782; }","duration":"329.149176ms","start":"2026-05-30T09:51:12.82327Z","end":"2026-05-30T09:51:13.152419Z","steps":["trace[487003801] 'agreement among raft nodes before linearized reading' (duration: 329.034394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.152454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.823251Z","time spent":"329.195697ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-30T09:51:13.152617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.328618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-30T09:51:13.152654Z","caller":"traceutil/trace.go:171","msg":"trace[847662812] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152; range_end:; response_count:1; response_revision:4782; }","duration":"411.3887ms","start":"2026-05-30T09:51:12.741248Z","end":"2026-05-30T09:51:13.152637Z","steps":["trace[847662812] 'agreement among raft nodes before linearized reading' (duration: 411.317999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.152679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.741226Z","time spent":"411.446991ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152\" "} {"level":"warn","ts":"2026-05-30T09:51:13.152808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.16082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T09:51:13.152833Z","caller":"traceutil/trace.go:171","msg":"trace[952486215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4782; }","duration":"770.228681ms","start":"2026-05-30T09:51:12.382599Z","end":"2026-05-30T09:51:13.152827Z","steps":["trace[952486215] 'agreement among raft nodes before linearized reading' (duration: 770.16175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.152851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.382577Z","time spent":"770.269032ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:13.152811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.939237ms","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-30T09:51:13.152953Z","caller":"traceutil/trace.go:171","msg":"trace[473836554] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4782; }","duration":"586.08015ms","start":"2026-05-30T09:51:12.566837Z","end":"2026-05-30T09:51:13.152917Z","steps":["trace[473836554] 'agreement among raft nodes before linearized reading' (duration: 585.876766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.153038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.566768Z","time spent":"586.259074ms","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:13.153086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.866804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-30T09:51:13.153113Z","caller":"traceutil/trace.go:171","msg":"trace[1692118398] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4782; }","duration":"485.935005ms","start":"2026-05-30T09:51:12.667172Z","end":"2026-05-30T09:51:13.153107Z","steps":["trace[1692118398] 'agreement among raft nodes before linearized reading' (duration: 485.867164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.153131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.667148Z","time spent":"485.978945ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-30T09:51:13.153121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.399366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:51:13.153393Z","caller":"traceutil/trace.go:171","msg":"trace[1347684650] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4782; }","duration":"413.762932ms","start":"2026-05-30T09:51:12.739619Z","end":"2026-05-30T09:51:13.153382Z","steps":["trace[1347684650] 'agreement among raft nodes before linearized reading' (duration: 413.410346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.153518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:12.739594Z","time spent":"413.862144ms","remote":"127.0.0.1:52276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:13.717133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.94068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:51:13.717247Z","caller":"traceutil/trace.go:171","msg":"trace[2132369131] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4975; }","duration":"483.704175ms","start":"2026-05-30T09:51:13.233523Z","end":"2026-05-30T09:51:13.717227Z","steps":["trace[2132369131] 'read index received' (duration: 56.085693ms)","trace[2132369131] 'applied index is now lower than readState.Index' (duration: 427.617372ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:13.717335Z","caller":"traceutil/trace.go:171","msg":"trace[104665598] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"494.326196ms","start":"2026-05-30T09:51:13.222972Z","end":"2026-05-30T09:51:13.717298Z","steps":["trace[104665598] 'process raft request' (duration: 67.077571ms)","trace[104665598] 'compare' (duration: 426.311719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:13.717523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.222961Z","time spent":"494.457128ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:13.717506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.227109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:51:13.717586Z","caller":"traceutil/trace.go:171","msg":"trace[1950897665] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4793; }","duration":"192.365703ms","start":"2026-05-30T09:51:13.525202Z","end":"2026-05-30T09:51:13.717568Z","steps":["trace[1950897665] 'agreement among raft nodes before linearized reading' (duration: 192.182519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.71737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.775972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-05-30T09:51:13.717623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.269455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-30T09:51:13.717753Z","caller":"traceutil/trace.go:171","msg":"trace[171647454] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4793; }","duration":"496.1558ms","start":"2026-05-30T09:51:13.221584Z","end":"2026-05-30T09:51:13.71774Z","steps":["trace[171647454] 'agreement among raft nodes before linearized reading' (duration: 495.738012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.717766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.777938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:51:13.717794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.22154Z","time spent":"496.241972ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-30T09:51:13.717807Z","caller":"traceutil/trace.go:171","msg":"trace[1726249855] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4793; }","duration":"416.84941ms","start":"2026-05-30T09:51:13.300946Z","end":"2026-05-30T09:51:13.717795Z","steps":["trace[1726249855] 'agreement among raft nodes before linearized reading' (duration: 416.790489ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:13.717813Z","caller":"traceutil/trace.go:171","msg":"trace[1218299794] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4793; }","duration":"461.51363ms","start":"2026-05-30T09:51:13.25626Z","end":"2026-05-30T09:51:13.717773Z","steps":["trace[1218299794] 'agreement among raft nodes before linearized reading' (duration: 461.095961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:13.717838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.300923Z","time spent":"416.908701ms","remote":"127.0.0.1:52564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-30T09:51:13.717864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.256236Z","time spent":"461.616251ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3530,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-30T09:51:14.265429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.234914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:51:14.266096Z","caller":"traceutil/trace.go:171","msg":"trace[1470037393] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"541.502732ms","start":"2026-05-30T09:51:13.724559Z","end":"2026-05-30T09:51:14.266061Z","steps":["trace[1470037393] 'process raft request' (duration: 116.552106ms)","trace[1470037393] 'compare' (duration: 423.901338ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:14.266246Z","caller":"traceutil/trace.go:171","msg":"trace[930369560] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4976; }","duration":"541.37401ms","start":"2026-05-30T09:51:13.724854Z","end":"2026-05-30T09:51:14.266228Z","steps":["trace[930369560] 'read index received' (duration: 44.990475ms)","trace[930369560] 'applied index is now lower than readState.Index' (duration: 496.382395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:14.266297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.724545Z","time spent":"541.625364ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:14.266403Z","caller":"traceutil/trace.go:171","msg":"trace[1511969240] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"533.960337ms","start":"2026-05-30T09:51:13.732409Z","end":"2026-05-30T09:51:14.26637Z","steps":["trace[1511969240] 'process raft request' (duration: 533.763993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.266436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.570524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-30T09:51:14.266511Z","caller":"traceutil/trace.go:171","msg":"trace[906502900] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4798; }","duration":"541.661125ms","start":"2026-05-30T09:51:13.72484Z","end":"2026-05-30T09:51:14.266501Z","steps":["trace[906502900] 'agreement among raft nodes before linearized reading' (duration: 541.504572ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.266327Z","caller":"traceutil/trace.go:171","msg":"trace[2054893022] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"540.687928ms","start":"2026-05-30T09:51:13.72563Z","end":"2026-05-30T09:51:14.266318Z","steps":["trace[2054893022] 'process raft request' (duration: 540.495505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.26655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.724835Z","time spent":"541.705996ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3530,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-30T09:51:14.266588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.725623Z","time spent":"540.924613ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:14.266503Z","caller":"traceutil/trace.go:171","msg":"trace[120469976] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"541.586304ms","start":"2026-05-30T09:51:13.7249Z","end":"2026-05-30T09:51:14.266486Z","steps":["trace[120469976] 'process raft request' (duration: 540.619847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.266684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.732378Z","time spent":"534.11724ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.266771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.724888Z","time spent":"541.844339ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.364217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.636754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:51:14.36424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.831503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-30T09:51:14.364319Z","caller":"traceutil/trace.go:171","msg":"trace[2039607816] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:4799; }","duration":"637.960006ms","start":"2026-05-30T09:51:13.726341Z","end":"2026-05-30T09:51:14.364301Z","steps":["trace[2039607816] 'agreement among raft nodes before linearized reading' (duration: 637.767763ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.364313Z","caller":"traceutil/trace.go:171","msg":"trace[2009296608] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4799; }","duration":"379.793166ms","start":"2026-05-30T09:51:13.984496Z","end":"2026-05-30T09:51:14.364289Z","steps":["trace[2009296608] 'agreement among raft nodes before linearized reading' (duration: 379.589902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.364365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.469349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-30T09:51:14.364369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.726327Z","time spent":"638.034437ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-05-30T09:51:14.364415Z","caller":"traceutil/trace.go:171","msg":"trace[388165798] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152; range_end:; response_count:1; response_revision:4799; }","duration":"206.54322ms","start":"2026-05-30T09:51:14.157853Z","end":"2026-05-30T09:51:14.364396Z","steps":["trace[388165798] 'agreement among raft nodes before linearized reading' (duration: 206.431728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.364434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.984479Z","time spent":"379.914058ms","remote":"127.0.0.1:44146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:14.364431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.930866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-30T09:51:14.36452Z","caller":"traceutil/trace.go:171","msg":"trace[761292106] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4799; }","duration":"217.056848ms","start":"2026-05-30T09:51:14.147443Z","end":"2026-05-30T09:51:14.3645Z","steps":["trace[761292106] 'agreement among raft nodes before linearized reading' (duration: 216.861925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.364615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.291772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-30T09:51:14.365451Z","caller":"traceutil/trace.go:171","msg":"trace[294981485] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4799; }","duration":"639.135248ms","start":"2026-05-30T09:51:13.726297Z","end":"2026-05-30T09:51:14.365433Z","steps":["trace[294981485] 'agreement among raft nodes before linearized reading' (duration: 637.939796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.365505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.726293Z","time spent":"639.200169ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-30T09:51:14.364748Z","caller":"traceutil/trace.go:171","msg":"trace[1581406644] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"395.08576ms","start":"2026-05-30T09:51:13.96965Z","end":"2026-05-30T09:51:14.364736Z","steps":["trace[1581406644] 'process raft request' (duration: 394.201683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.364227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.263199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:14.365655Z","caller":"traceutil/trace.go:171","msg":"trace[1246538135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4799; }","duration":"450.744186ms","start":"2026-05-30T09:51:13.914895Z","end":"2026-05-30T09:51:14.365639Z","steps":["trace[1246538135] 'agreement among raft nodes before linearized reading' (duration: 449.243599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.365718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.914853Z","time spent":"450.850409ms","remote":"127.0.0.1:52106","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-30T09:51:14.364956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.429676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-30T09:51:14.365884Z","caller":"traceutil/trace.go:171","msg":"trace[1094389125] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4799; }","duration":"354.349912ms","start":"2026-05-30T09:51:14.011459Z","end":"2026-05-30T09:51:14.365809Z","steps":["trace[1094389125] 'agreement among raft nodes before linearized reading' (duration: 353.351125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.365923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.011454Z","time spent":"354.458434ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-30T09:51:14.365078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.944674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-30T09:51:14.366139Z","caller":"traceutil/trace.go:171","msg":"trace[1476550934] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4799; }","duration":"640.755077ms","start":"2026-05-30T09:51:13.725369Z","end":"2026-05-30T09:51:14.366124Z","steps":["trace[1476550934] 'agreement among raft nodes before linearized reading' (duration: 638.861363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.3662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.725364Z","time spent":"640.821928ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-30T09:51:14.365704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.969624Z","time spent":"395.925045ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.637402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.499377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:51:14.6375Z","caller":"traceutil/trace.go:171","msg":"trace[1015729318] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4799; }","duration":"786.658521ms","start":"2026-05-30T09:51:13.850815Z","end":"2026-05-30T09:51:14.637473Z","steps":["trace[1015729318] 'agreement among raft nodes before linearized reading' (duration: 513.367937ms)","trace[1015729318] 'count revisions from in-memory index tree' (duration: 273.120091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:14.637555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:13.850794Z","time spent":"786.746642ms","remote":"127.0.0.1:52614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":10,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:51:14.637959Z","caller":"traceutil/trace.go:171","msg":"trace[1868621671] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"363.929183ms","start":"2026-05-30T09:51:14.274002Z","end":"2026-05-30T09:51:14.637931Z","steps":["trace[1868621671] 'process raft request' (duration: 358.272251ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.637976Z","caller":"traceutil/trace.go:171","msg":"trace[1321985473] linearizableReadLoop","detail":"{readStateIndex:4986; appliedIndex:4982; }","duration":"273.931015ms","start":"2026-05-30T09:51:14.364016Z","end":"2026-05-30T09:51:14.637947Z","steps":["trace[1321985473] 'read index received' (duration: 268.253993ms)","trace[1321985473] 'applied index is now lower than readState.Index' (duration: 5.675962ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:14.638127Z","caller":"traceutil/trace.go:171","msg":"trace[409077160] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"363.18164ms","start":"2026-05-30T09:51:14.274932Z","end":"2026-05-30T09:51:14.638114Z","steps":["trace[409077160] 'process raft request' (duration: 362.916485ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.638003Z","caller":"traceutil/trace.go:171","msg":"trace[640983673] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"363.441475ms","start":"2026-05-30T09:51:14.274548Z","end":"2026-05-30T09:51:14.637989Z","steps":["trace[640983673] 'process raft request' (duration: 363.18367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.638311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.274925Z","time spent":"363.274011ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.638322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.274541Z","time spent":"363.717079ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.638109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.273982Z","time spent":"364.039095ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:14.638263Z","caller":"traceutil/trace.go:171","msg":"trace[383219172] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"362.873504ms","start":"2026-05-30T09:51:14.275359Z","end":"2026-05-30T09:51:14.638232Z","steps":["trace[383219172] 'process raft request' (duration: 362.543888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.638987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.275129Z","time spent":"363.78625ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:14.640228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.062431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-05-30T09:51:14.640288Z","caller":"traceutil/trace.go:171","msg":"trace[801604767] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4803; }","duration":"366.138602ms","start":"2026-05-30T09:51:14.274131Z","end":"2026-05-30T09:51:14.64027Z","steps":["trace[801604767] 'agreement among raft nodes before linearized reading' (duration: 364.058945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.640323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:14.274124Z","time spent":"366.190213ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1370,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-05-30T09:51:14.645914Z","caller":"traceutil/trace.go:171","msg":"trace[1998668781] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"274.862381ms","start":"2026-05-30T09:51:14.371035Z","end":"2026-05-30T09:51:14.645898Z","steps":["trace[1998668781] 'process raft request' (duration: 274.713608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.64625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.333073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10559"} {"level":"warn","ts":"2026-05-30T09:51:14.646377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.398864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ad18e489-2c10-47a4-bdb8-4ac1e252edf2\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-30T09:51:14.646428Z","caller":"traceutil/trace.go:171","msg":"trace[1630706658] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ad18e489-2c10-47a4-bdb8-4ac1e252edf2; range_end:; response_count:1; response_revision:4804; }","duration":"278.454805ms","start":"2026-05-30T09:51:14.367948Z","end":"2026-05-30T09:51:14.646403Z","steps":["trace[1630706658] 'agreement among raft nodes before linearized reading' (duration: 278.384753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.646274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.593907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-05-30T09:51:14.646516Z","caller":"traceutil/trace.go:171","msg":"trace[652844648] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4804; }","duration":"274.866501ms","start":"2026-05-30T09:51:14.371637Z","end":"2026-05-30T09:51:14.646504Z","steps":["trace[652844648] 'agreement among raft nodes before linearized reading' (duration: 274.485065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.646335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.390753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:14.646625Z","caller":"traceutil/trace.go:171","msg":"trace[764168660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4804; }","duration":"278.734359ms","start":"2026-05-30T09:51:14.367877Z","end":"2026-05-30T09:51:14.646611Z","steps":["trace[764168660] 'agreement among raft nodes before linearized reading' (duration: 278.358663ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.64673Z","caller":"traceutil/trace.go:171","msg":"trace[1102963515] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4804; }","duration":"278.515995ms","start":"2026-05-30T09:51:14.367877Z","end":"2026-05-30T09:51:14.646393Z","steps":["trace[1102963515] 'agreement among raft nodes before linearized reading' (duration: 278.22041ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.907647Z","caller":"traceutil/trace.go:171","msg":"trace[159097287] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"235.439927ms","start":"2026-05-30T09:51:14.672183Z","end":"2026-05-30T09:51:14.907623Z","steps":["trace[159097287] 'read index received' (duration: 221.488428ms)","trace[159097287] 'applied index is now lower than readState.Index' (duration: 13.950559ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:14.90796Z","caller":"traceutil/trace.go:171","msg":"trace[51690493] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"237.694887ms","start":"2026-05-30T09:51:14.670251Z","end":"2026-05-30T09:51:14.907946Z","steps":["trace[51690493] 'process raft request' (duration: 223.477903ms)","trace[51690493] 'compare' (duration: 12.964422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:14.908128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.184588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-30T09:51:14.908186Z","caller":"traceutil/trace.go:171","msg":"trace[1265635941] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4806; }","duration":"233.274059ms","start":"2026-05-30T09:51:14.674899Z","end":"2026-05-30T09:51:14.908173Z","steps":["trace[1265635941] 'agreement among raft nodes before linearized reading' (duration: 233.090555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:14.908223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.012548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-05-30T09:51:14.908301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.08337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:51:14.908307Z","caller":"traceutil/trace.go:171","msg":"trace[1768419170] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4806; }","duration":"236.1192ms","start":"2026-05-30T09:51:14.672163Z","end":"2026-05-30T09:51:14.908282Z","steps":["trace[1768419170] 'agreement among raft nodes before linearized reading' (duration: 235.869734ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:14.908338Z","caller":"traceutil/trace.go:171","msg":"trace[809716517] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4806; }","duration":"203.168461ms","start":"2026-05-30T09:51:14.705158Z","end":"2026-05-30T09:51:14.908327Z","steps":["trace[809716517] 'agreement among raft nodes before linearized reading' (duration: 203.080909ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:17.558257Z","caller":"traceutil/trace.go:171","msg":"trace[1504914227] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"427.428813ms","start":"2026-05-30T09:51:17.13081Z","end":"2026-05-30T09:51:17.558239Z","steps":["trace[1504914227] 'process raft request' (duration: 427.331281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.558373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.130801Z","time spent":"427.509385ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3938,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:17.5656Z","caller":"traceutil/trace.go:171","msg":"trace[1498954574] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"403.788032ms","start":"2026-05-30T09:51:17.161786Z","end":"2026-05-30T09:51:17.565574Z","steps":["trace[1498954574] 'process raft request' (duration: 403.426796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.56573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.161778Z","time spent":"403.888804ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:17.566239Z","caller":"traceutil/trace.go:171","msg":"trace[691863797] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"396.784776ms","start":"2026-05-30T09:51:17.169444Z","end":"2026-05-30T09:51:17.566229Z","steps":["trace[691863797] 'process raft request' (duration: 395.919541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.169419Z","time spent":"396.850468ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:17.566388Z","caller":"traceutil/trace.go:171","msg":"trace[887052030] linearizableReadLoop","detail":"{readStateIndex:5022; appliedIndex:5019; }","duration":"340.015027ms","start":"2026-05-30T09:51:17.226365Z","end":"2026-05-30T09:51:17.56638Z","steps":["trace[887052030] 'read index received' (duration: 331.837173ms)","trace[887052030] 'applied index is now lower than readState.Index' (duration: 8.177084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:17.566545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.17831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:51:17.566572Z","caller":"traceutil/trace.go:171","msg":"trace[1091114918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4840; }","duration":"340.233842ms","start":"2026-05-30T09:51:17.226331Z","end":"2026-05-30T09:51:17.566565Z","steps":["trace[1091114918] 'agreement among raft nodes before linearized reading' (duration: 340.12993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.226314Z","time spent":"340.272723ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:17.56659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.587908ms","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-30T09:51:17.566616Z","caller":"traceutil/trace.go:171","msg":"trace[1776102525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4840; }","duration":"336.635868ms","start":"2026-05-30T09:51:17.229973Z","end":"2026-05-30T09:51:17.566609Z","steps":["trace[1776102525] 'agreement among raft nodes before linearized reading' (duration: 336.566076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.229959Z","time spent":"336.670999ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:17.566679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.406043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:51:17.566698Z","caller":"traceutil/trace.go:171","msg":"trace[1087771815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4840; }","duration":"332.433224ms","start":"2026-05-30T09:51:17.23426Z","end":"2026-05-30T09:51:17.566693Z","steps":["trace[1087771815] 'agreement among raft nodes before linearized reading' (duration: 332.383463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.234256Z","time spent":"332.453994ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:17.566772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.748431ms","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-30T09:51:17.566796Z","caller":"traceutil/trace.go:171","msg":"trace[118893348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4840; }","duration":"336.777641ms","start":"2026-05-30T09:51:17.230012Z","end":"2026-05-30T09:51:17.56679Z","steps":["trace[118893348] 'agreement among raft nodes before linearized reading' (duration: 336.7153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.197021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152\" ","response":"range_response_count:1 size:3953"} {"level":"warn","ts":"2026-05-30T09:51:17.566814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.23001Z","time spent":"336.798662ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T09:51:17.566819Z","caller":"traceutil/trace.go:171","msg":"trace[115239185] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33438fc8-8262-4f57-82ba-8129911f6152; range_end:; response_count:1; response_revision:4840; }","duration":"146.262703ms","start":"2026-05-30T09:51:17.420549Z","end":"2026-05-30T09:51:17.566812Z","steps":["trace[115239185] 'agreement among raft nodes before linearized reading' (duration: 146.207512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.149491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-30T09:51:17.566929Z","caller":"traceutil/trace.go:171","msg":"trace[579865425] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4840; }","duration":"335.177483ms","start":"2026-05-30T09:51:17.231746Z","end":"2026-05-30T09:51:17.566923Z","steps":["trace[579865425] 'agreement among raft nodes before linearized reading' (duration: 334.931088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:17.566946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:17.231742Z","time spent":"335.199993ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T09:51:21.27344Z","caller":"traceutil/trace.go:171","msg":"trace[227159863] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"114.671157ms","start":"2026-05-30T09:51:21.158745Z","end":"2026-05-30T09:51:21.273416Z","steps":["trace[227159863] 'process raft request' (duration: 80.649556ms)","trace[227159863] 'compare' (duration: 33.900249ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:22.37791Z","caller":"traceutil/trace.go:171","msg":"trace[1078419494] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"123.719274ms","start":"2026-05-30T09:51:22.254161Z","end":"2026-05-30T09:51:22.377881Z","steps":["trace[1078419494] 'process raft request' (duration: 105.336649ms)","trace[1078419494] 'compare' (duration: 18.203992ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:22.377981Z","caller":"traceutil/trace.go:171","msg":"trace[42476107] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"122.094625ms","start":"2026-05-30T09:51:22.255868Z","end":"2026-05-30T09:51:22.377962Z","steps":["trace[42476107] 'process raft request' (duration: 122.020554ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:22.378196Z","caller":"traceutil/trace.go:171","msg":"trace[52784901] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"122.402061ms","start":"2026-05-30T09:51:22.255759Z","end":"2026-05-30T09:51:22.378162Z","steps":["trace[52784901] 'process raft request' (duration: 122.102716ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:22.378221Z","caller":"traceutil/trace.go:171","msg":"trace[2005727940] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5107; }","duration":"122.398571ms","start":"2026-05-30T09:51:22.255796Z","end":"2026-05-30T09:51:22.378194Z","steps":["trace[2005727940] 'read index received' (duration: 103.670281ms)","trace[2005727940] 'applied index is now lower than readState.Index' (duration: 18.72424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:22.37846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.649926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-40efa9eb-e9be-46a7-b325-4c8d75aaaed4\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-30T09:51:22.378493Z","caller":"traceutil/trace.go:171","msg":"trace[1323883592] range","detail":"{range_begin:/registry/persistentvolumes/pvc-40efa9eb-e9be-46a7-b325-4c8d75aaaed4; range_end:; response_count:1; response_revision:4925; }","duration":"122.718966ms","start":"2026-05-30T09:51:22.255764Z","end":"2026-05-30T09:51:22.378483Z","steps":["trace[1323883592] 'agreement among raft nodes before linearized reading' (duration: 122.608964ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:22.378586Z","caller":"traceutil/trace.go:171","msg":"trace[2131670989] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"116.323063ms","start":"2026-05-30T09:51:22.262251Z","end":"2026-05-30T09:51:22.378574Z","steps":["trace[2131670989] 'process raft request' (duration: 115.667652ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:22.378648Z","caller":"traceutil/trace.go:171","msg":"trace[1232800177] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"122.967221ms","start":"2026-05-30T09:51:22.255676Z","end":"2026-05-30T09:51:22.378643Z","steps":["trace[1232800177] 'process raft request' (duration: 122.146807ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:26.488491Z","caller":"traceutil/trace.go:171","msg":"trace[1645196882] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"147.349196ms","start":"2026-05-30T09:51:26.341126Z","end":"2026-05-30T09:51:26.488475Z","steps":["trace[1645196882] 'read index received' (duration: 147.277715ms)","trace[1645196882] 'applied index is now lower than readState.Index' (duration: 70.701µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:26.488591Z","caller":"traceutil/trace.go:171","msg":"trace[340497977] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"232.207086ms","start":"2026-05-30T09:51:26.256356Z","end":"2026-05-30T09:51:26.488564Z","steps":["trace[340497977] 'process raft request' (duration: 231.991523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:26.488723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.57964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-30T09:51:26.488775Z","caller":"traceutil/trace.go:171","msg":"trace[351550337] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4957; }","duration":"147.669532ms","start":"2026-05-30T09:51:26.341086Z","end":"2026-05-30T09:51:26.488756Z","steps":["trace[351550337] 'agreement among raft nodes before linearized reading' (duration: 147.487929ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:26.634273Z","caller":"traceutil/trace.go:171","msg":"trace[357442911] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"116.23421ms","start":"2026-05-30T09:51:26.51801Z","end":"2026-05-30T09:51:26.634244Z","steps":["trace[357442911] 'process raft request' (duration: 82.886034ms)","trace[357442911] 'compare' (duration: 33.163253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:27.812209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.041251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:51:27.812582Z","caller":"traceutil/trace.go:171","msg":"trace[817538387] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"133.903057ms","start":"2026-05-30T09:51:27.67864Z","end":"2026-05-30T09:51:27.812543Z","steps":["trace[817538387] 'process raft request' (duration: 32.402669ms)","trace[817538387] 'compare' (duration: 100.876698ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:27.812709Z","caller":"traceutil/trace.go:171","msg":"trace[1750913547] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"133.987739ms","start":"2026-05-30T09:51:27.678698Z","end":"2026-05-30T09:51:27.812686Z","steps":["trace[1750913547] 'process raft request' (duration: 133.637683ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:27.813496Z","caller":"traceutil/trace.go:171","msg":"trace[1731915786] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"127.886752ms","start":"2026-05-30T09:51:27.685595Z","end":"2026-05-30T09:51:27.813482Z","steps":["trace[1731915786] 'process raft request' (duration: 127.760309ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:27.813605Z","caller":"traceutil/trace.go:171","msg":"trace[1717654414] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"133.301306ms","start":"2026-05-30T09:51:27.680215Z","end":"2026-05-30T09:51:27.813517Z","steps":["trace[1717654414] 'process raft request' (duration: 133.064552ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:27.813857Z","caller":"traceutil/trace.go:171","msg":"trace[1560835575] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"133.928067ms","start":"2026-05-30T09:51:27.679917Z","end":"2026-05-30T09:51:27.813845Z","steps":["trace[1560835575] 'process raft request' (duration: 133.218815ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:04.130676Z","caller":"traceutil/trace.go:171","msg":"trace[667492600] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"104.507488ms","start":"2026-05-30T09:52:04.026141Z","end":"2026-05-30T09:52:04.130649Z","steps":["trace[667492600] 'process raft request' (duration: 97.801586ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:04.133758Z","caller":"traceutil/trace.go:171","msg":"trace[232713243] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"104.269016ms","start":"2026-05-30T09:52:04.02947Z","end":"2026-05-30T09:52:04.133739Z","steps":["trace[232713243] 'process raft request' (duration: 104.027361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:04.134043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.088871ms","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-30T09:52:04.134102Z","caller":"traceutil/trace.go:171","msg":"trace[380219027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5304; }","duration":"101.182893ms","start":"2026-05-30T09:52:04.032906Z","end":"2026-05-30T09:52:04.134088Z","steps":["trace[380219027] 'agreement among raft nodes before linearized reading' (duration: 100.904629ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:04.133675Z","caller":"traceutil/trace.go:171","msg":"trace[980572008] linearizableReadLoop","detail":"{readStateIndex:5501; appliedIndex:5499; }","duration":"100.718255ms","start":"2026-05-30T09:52:04.03293Z","end":"2026-05-30T09:52:04.133648Z","steps":["trace[980572008] 'read index received' (duration: 91.135364ms)","trace[980572008] 'applied index is now lower than readState.Index' (duration: 9.581801ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:27.898957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2816} {"level":"info","ts":"2026-05-30T09:52:27.986604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2816,"took":"86.060435ms","hash":4254276538,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":28827648,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-30T09:52:27.986655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4254276538,"revision":2816,"compact-revision":-1} {"level":"info","ts":"2026-05-30T09:52:30.242967Z","caller":"traceutil/trace.go:171","msg":"trace[776694614] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"101.325561ms","start":"2026-05-30T09:52:30.141612Z","end":"2026-05-30T09:52:30.242938Z","steps":["trace[776694614] 'process raft request' (duration: 50.377531ms)","trace[776694614] 'compare' (duration: 50.802378ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:30.466766Z","caller":"traceutil/trace.go:171","msg":"trace[1740771983] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"160.327604ms","start":"2026-05-30T09:52:30.306411Z","end":"2026-05-30T09:52:30.466739Z","steps":["trace[1740771983] 'process raft request' (duration: 139.211199ms)","trace[1740771983] 'compare' (duration: 20.899092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:54:58.045435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.878162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:54:58.045561Z","caller":"traceutil/trace.go:171","msg":"trace[200941274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6523; }","duration":"131.032224ms","start":"2026-05-30T09:54:57.914506Z","end":"2026-05-30T09:54:58.045538Z","steps":["trace[200941274] 'range keys from in-memory index tree' (duration: 130.774099ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:55:17.778724Z","caller":"traceutil/trace.go:171","msg":"trace[1307369183] transaction","detail":"{read_only:false; response_revision:6680; number_of_response:1; }","duration":"144.954559ms","start":"2026-05-30T09:55:17.633743Z","end":"2026-05-30T09:55:17.778698Z","steps":["trace[1307369183] 'process raft request' (duration: 144.817347ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:55:17.927534Z","caller":"traceutil/trace.go:171","msg":"trace[587027239] transaction","detail":"{read_only:false; response_revision:6681; number_of_response:1; }","duration":"120.508846ms","start":"2026-05-30T09:55:17.807008Z","end":"2026-05-30T09:55:17.927517Z","steps":["trace[587027239] 'process raft request' (duration: 94.179694ms)","trace[587027239] 'compare' (duration: 25.998187ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:57:27.906198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5480} {"level":"info","ts":"2026-05-30T09:57:27.983688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5480,"took":"75.977608ms","hash":2468037062,"current-db-size-bytes":53575680,"current-db-size":"54 MB","current-db-size-in-use-bytes":18575360,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-30T09:57:27.98374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2468037062,"revision":5480,"compact-revision":2816}