{"level":"warn","ts":"2026-09-18T15:20:26.116506Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:20:26.116608Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.124: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://162.253.55.124:2380","--initial-cluster=instance=https://162.253.55.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.124: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-09-18T15:20:26.116702Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:20:26.116714Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.124:2380"]} {"level":"info","ts":"2026-09-18T15:20:26.116786Z","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-09-18T15:20:26.117656Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"]} {"level":"info","ts":"2026-09-18T15:20:26.11778Z","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://162.253.55.124:2380"],"listen-peer-urls":["https://162.253.55.124:2380"],"advertise-client-urls":["https://162.253.55.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.124: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-09-18T15:20:26.122964Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.886333ms"} {"level":"info","ts":"2026-09-18T15:20:26.133465Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ad1df4dc3f3835d","cluster-id":"5f6d28152804218"} {"level":"info","ts":"2026-09-18T15:20:26.133752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:20:26.133843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became follower at term 0"} {"level":"info","ts":"2026-09-18T15:20:26.133876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ad1df4dc3f3835d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:20:26.133937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became follower at term 1"} {"level":"info","ts":"2026-09-18T15:20:26.134015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=(11155943277066421085)"} {"level":"warn","ts":"2026-09-18T15:20:26.140297Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:20:26.14157Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:20:26.142526Z","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-09-18T15:20:26.144058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ad1df4dc3f3835d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:20:26.144211Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ad1df4dc3f3835d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:20:26.144407Z","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-09-18T15:20:26.144894Z","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-09-18T15:20:26.144968Z","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-09-18T15:20:26.145426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=(11155943277066421085)"} {"level":"info","ts":"2026-09-18T15:20:26.145673Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f6d28152804218","local-member-id":"9ad1df4dc3f3835d","added-peer-id":"9ad1df4dc3f3835d","added-peer-peer-urls":["https://162.253.55.124:2380"]} {"level":"info","ts":"2026-09-18T15:20:26.149201Z","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-09-18T15:20:26.149351Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.124:2380"} {"level":"info","ts":"2026-09-18T15:20:26.149369Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.124:2380"} {"level":"info","ts":"2026-09-18T15:20:26.149764Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:20:26.149707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ad1df4dc3f3835d","initial-advertise-peer-urls":["https://162.253.55.124:2380"],"listen-peer-urls":["https://162.253.55.124:2380"],"advertise-client-urls":["https://162.253.55.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:20:26.334396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:20:26.334469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:20:26.334495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d received MsgPreVoteResp from 9ad1df4dc3f3835d at term 1"} {"level":"info","ts":"2026-09-18T15:20:26.334507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:20:26.334512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d received MsgVoteResp from 9ad1df4dc3f3835d at term 2"} {"level":"info","ts":"2026-09-18T15:20:26.33452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became leader at term 2"} {"level":"info","ts":"2026-09-18T15:20:26.334541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ad1df4dc3f3835d elected leader 9ad1df4dc3f3835d at term 2"} {"level":"info","ts":"2026-09-18T15:20:26.335517Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:20:26.336319Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ad1df4dc3f3835d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.124:2379]}","request-path":"/0/members/9ad1df4dc3f3835d/attributes","cluster-id":"5f6d28152804218","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:20:26.336341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:20:26.336783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:20:26.336804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:20:26.337381Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f6d28152804218","local-member-id":"9ad1df4dc3f3835d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:20:26.3375Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:20:26.337558Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:20:26.34013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.124:2379"} {"level":"info","ts":"2026-09-18T15:20:26.340327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:20:26.344518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:20:29.629698Z","caller":"traceutil/trace.go:171","msg":"trace[2034124886] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"183.947568ms","start":"2026-09-18T15:20:29.445726Z","end":"2026-09-18T15:20:29.629674Z","steps":["trace[2034124886] 'process raft request' (duration: 179.893491ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:00.464654Z","caller":"traceutil/trace.go:171","msg":"trace[1219969640] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"121.685875ms","start":"2026-09-18T15:21:00.34295Z","end":"2026-09-18T15:21:00.464636Z","steps":["trace[1219969640] 'process raft request' (duration: 121.574931ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:01.60099Z","caller":"traceutil/trace.go:171","msg":"trace[276877899] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"139.3195ms","start":"2026-09-18T15:21:01.461646Z","end":"2026-09-18T15:21:01.600965Z","steps":["trace[276877899] 'process raft request' (duration: 139.175636ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:11.583732Z","caller":"traceutil/trace.go:171","msg":"trace[1462118028] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"491.186839ms","start":"2026-09-18T15:21:11.092527Z","end":"2026-09-18T15:21:11.583714Z","steps":["trace[1462118028] 'process raft request' (duration: 482.008423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:11.58414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:11.092502Z","time spent":"491.286932ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:21:11.584807Z","caller":"traceutil/trace.go:171","msg":"trace[296627000] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"491.189288ms","start":"2026-09-18T15:21:11.093591Z","end":"2026-09-18T15:21:11.584781Z","steps":["trace[296627000] 'process raft request' (duration: 490.977142ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:11.584845Z","caller":"traceutil/trace.go:171","msg":"trace[34509066] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"417.177155ms","start":"2026-09-18T15:21:11.167651Z","end":"2026-09-18T15:21:11.584829Z","steps":["trace[34509066] 'process raft request' (duration: 417.116113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:11.584888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:11.093557Z","time spent":"491.291381ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:21:11.584884Z","caller":"traceutil/trace.go:171","msg":"trace[431637963] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"488.996733ms","start":"2026-09-18T15:21:11.095864Z","end":"2026-09-18T15:21:11.584861Z","steps":["trace[431637963] 'process raft request' (duration: 488.820298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:11.584935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:11.167632Z","time spent":"417.257898ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:21:11.585054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:11.095845Z","time spent":"489.088895ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:21:15.992139Z","caller":"traceutil/trace.go:171","msg":"trace[1483532935] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"107.420817ms","start":"2026-09-18T15:21:15.884696Z","end":"2026-09-18T15:21:15.992117Z","steps":["trace[1483532935] 'process raft request' (duration: 106.988215ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:18.11001Z","caller":"traceutil/trace.go:171","msg":"trace[1478233575] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"105.945853ms","start":"2026-09-18T15:21:18.004022Z","end":"2026-09-18T15:21:18.109968Z","steps":["trace[1478233575] 'read index received' (duration: 98.880946ms)","trace[1478233575] 'applied index is now lower than readState.Index' (duration: 7.063407ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:18.110149Z","caller":"traceutil/trace.go:171","msg":"trace[1399819602] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"168.002826ms","start":"2026-09-18T15:21:17.942136Z","end":"2026-09-18T15:21:18.110139Z","steps":["trace[1399819602] 'process raft request' (duration: 161.490843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:18.110492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.444398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:21:18.110638Z","caller":"traceutil/trace.go:171","msg":"trace[1144703140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"106.635963ms","start":"2026-09-18T15:21:18.003974Z","end":"2026-09-18T15:21:18.11061Z","steps":["trace[1144703140] 'agreement among raft nodes before linearized reading' (duration: 106.335315ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:18.404773Z","caller":"traceutil/trace.go:171","msg":"trace[1394042870] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"249.042864ms","start":"2026-09-18T15:21:18.155642Z","end":"2026-09-18T15:21:18.404685Z","steps":["trace[1394042870] 'process raft request' (duration: 211.176516ms)","trace[1394042870] 'compare' (duration: 37.663723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:21:19.278799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.986368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:21:19.279008Z","caller":"traceutil/trace.go:171","msg":"trace[2137609494] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"128.002895ms","start":"2026-09-18T15:21:19.150988Z","end":"2026-09-18T15:21:19.278991Z","steps":["trace[2137609494] 'process raft request' (duration: 127.936123ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:19.278991Z","caller":"traceutil/trace.go:171","msg":"trace[246801454] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.090014617s","start":"2026-09-18T15:21:18.188938Z","end":"2026-09-18T15:21:19.278952Z","steps":["trace[246801454] 'process raft request' (duration: 438.607696ms)","trace[246801454] 'compare' (duration: 650.507356ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:19.279052Z","caller":"traceutil/trace.go:171","msg":"trace[2041537070] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"954.022474ms","start":"2026-09-18T15:21:18.324961Z","end":"2026-09-18T15:21:19.278983Z","steps":["trace[2041537070] 'read index received' (duration: 41.804049ms)","trace[2041537070] 'applied index is now lower than readState.Index' (duration: 912.215225ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:21:19.279109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:18.188918Z","time spent":"1.09013775s","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:21:19.279514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.531427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T15:21:19.27953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.919119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:21:19.279559Z","caller":"traceutil/trace.go:171","msg":"trace[797026431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"275.973061ms","start":"2026-09-18T15:21:19.003577Z","end":"2026-09-18T15:21:19.27955Z","steps":["trace[797026431] 'agreement among raft nodes before linearized reading' (duration: 275.92822ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:19.279561Z","caller":"traceutil/trace.go:171","msg":"trace[1545564481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"954.61822ms","start":"2026-09-18T15:21:18.324927Z","end":"2026-09-18T15:21:19.279545Z","steps":["trace[1545564481] 'agreement among raft nodes before linearized reading' (duration: 954.474126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:19.279584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.60851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-18T15:21:19.279596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:18.324914Z","time spent":"954.672691ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T15:21:19.279627Z","caller":"traceutil/trace.go:171","msg":"trace[1104815015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"698.679682ms","start":"2026-09-18T15:21:18.580936Z","end":"2026-09-18T15:21:19.279615Z","steps":["trace[1104815015] 'agreement among raft nodes before linearized reading' (duration: 698.545618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:19.279657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:21:18.58088Z","time spent":"698.770405ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T15:21:19.498098Z","caller":"traceutil/trace.go:171","msg":"trace[1833312863] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"211.950648ms","start":"2026-09-18T15:21:19.286126Z","end":"2026-09-18T15:21:19.498077Z","steps":["trace[1833312863] 'read index received' (duration: 136.266762ms)","trace[1833312863] 'applied index is now lower than readState.Index' (duration: 75.683056ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:19.498189Z","caller":"traceutil/trace.go:171","msg":"trace[1601396800] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"214.230772ms","start":"2026-09-18T15:21:19.283947Z","end":"2026-09-18T15:21:19.498178Z","steps":["trace[1601396800] 'process raft request' (duration: 138.435633ms)","trace[1601396800] 'compare' (duration: 75.518201ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:21:19.498289Z","caller":"traceutil/trace.go:171","msg":"trace[999170769] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"212.00644ms","start":"2026-09-18T15:21:19.286247Z","end":"2026-09-18T15:21:19.498254Z","steps":["trace[999170769] 'process raft request' (duration: 211.793534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:19.498547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.40032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-18T15:21:19.498575Z","caller":"traceutil/trace.go:171","msg":"trace[1770194486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:619; }","duration":"212.475222ms","start":"2026-09-18T15:21:19.286091Z","end":"2026-09-18T15:21:19.498566Z","steps":["trace[1770194486] 'agreement among raft nodes before linearized reading' (duration: 212.067371ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:21:21.350362Z","caller":"traceutil/trace.go:171","msg":"trace[147064706] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"129.793233ms","start":"2026-09-18T15:21:21.220527Z","end":"2026-09-18T15:21:21.35032Z","steps":["trace[147064706] 'process raft request' (duration: 129.69909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:21:35.037301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.564775ms","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-09-18T15:21:35.037472Z","caller":"traceutil/trace.go:171","msg":"trace[877160924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"123.802012ms","start":"2026-09-18T15:21:34.913639Z","end":"2026-09-18T15:21:35.037441Z","steps":["trace[877160924] 'range keys from in-memory index tree' (duration: 123.33447ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:04.752615Z","caller":"traceutil/trace.go:171","msg":"trace[1271561044] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"239.621756ms","start":"2026-09-18T15:22:04.512973Z","end":"2026-09-18T15:22:04.752595Z","steps":["trace[1271561044] 'process raft request' (duration: 239.514633ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:15.939039Z","caller":"traceutil/trace.go:171","msg":"trace[1365151904] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"117.795617ms","start":"2026-09-18T15:22:15.821186Z","end":"2026-09-18T15:22:15.938982Z","steps":["trace[1365151904] 'process raft request' (duration: 102.950879ms)","trace[1365151904] 'compare' (duration: 14.579394ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:16.12574Z","caller":"traceutil/trace.go:171","msg":"trace[1655782695] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"132.438968ms","start":"2026-09-18T15:22:15.993277Z","end":"2026-09-18T15:22:16.125716Z","steps":["trace[1655782695] 'process raft request' (duration: 74.215233ms)","trace[1655782695] 'compare' (duration: 57.96985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:22:16.125959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.297059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:22:16.125978Z","caller":"traceutil/trace.go:171","msg":"trace[1940970550] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"123.008953ms","start":"2026-09-18T15:22:16.002634Z","end":"2026-09-18T15:22:16.125643Z","steps":["trace[1940970550] 'read index received' (duration: 64.816789ms)","trace[1940970550] 'applied index is now lower than readState.Index' (duration: 58.190994ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:16.126024Z","caller":"traceutil/trace.go:171","msg":"trace[775770242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"123.39701ms","start":"2026-09-18T15:22:16.002612Z","end":"2026-09-18T15:22:16.126009Z","steps":["trace[775770242] 'agreement among raft nodes before linearized reading' (duration: 123.258758ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:17.604401Z","caller":"traceutil/trace.go:171","msg":"trace[961921466] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"171.393211ms","start":"2026-09-18T15:22:17.432987Z","end":"2026-09-18T15:22:17.60438Z","steps":["trace[961921466] 'process raft request' (duration: 171.189687ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:18.157426Z","caller":"traceutil/trace.go:171","msg":"trace[1906093018] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"133.214312ms","start":"2026-09-18T15:22:18.024184Z","end":"2026-09-18T15:22:18.157398Z","steps":["trace[1906093018] 'process raft request' (duration: 70.693256ms)","trace[1906093018] 'compare' (duration: 62.20833ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:18.374688Z","caller":"traceutil/trace.go:171","msg":"trace[362359896] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"131.43745ms","start":"2026-09-18T15:22:18.243212Z","end":"2026-09-18T15:22:18.37465Z","steps":["trace[362359896] 'process raft request' (duration: 104.609323ms)","trace[362359896] 'compare' (duration: 26.601584ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:18.667285Z","caller":"traceutil/trace.go:171","msg":"trace[1017085681] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"147.616327ms","start":"2026-09-18T15:22:18.519642Z","end":"2026-09-18T15:22:18.667258Z","steps":["trace[1017085681] 'process raft request' (duration: 147.334261ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:18.888586Z","caller":"traceutil/trace.go:171","msg":"trace[408467705] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"116.589348ms","start":"2026-09-18T15:22:18.771963Z","end":"2026-09-18T15:22:18.888553Z","steps":["trace[408467705] 'process raft request' (duration: 116.13341ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:19.193251Z","caller":"traceutil/trace.go:171","msg":"trace[2012673822] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"100.564952ms","start":"2026-09-18T15:22:19.092662Z","end":"2026-09-18T15:22:19.193227Z","steps":["trace[2012673822] 'process raft request' (duration: 100.369609ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:19.713396Z","caller":"traceutil/trace.go:171","msg":"trace[1531423230] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"135.056048ms","start":"2026-09-18T15:22:19.578316Z","end":"2026-09-18T15:22:19.713372Z","steps":["trace[1531423230] 'process raft request' (duration: 134.912296ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:27.324771Z","caller":"traceutil/trace.go:171","msg":"trace[1627197846] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"194.68731ms","start":"2026-09-18T15:22:27.130065Z","end":"2026-09-18T15:22:27.324753Z","steps":["trace[1627197846] 'read index received' (duration: 194.612249ms)","trace[1627197846] 'applied index is now lower than readState.Index' (duration: 74.471µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:22:27.324942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.871542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-18T15:22:27.324905Z","caller":"traceutil/trace.go:171","msg":"trace[1071780348] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"264.513246ms","start":"2026-09-18T15:22:27.060366Z","end":"2026-09-18T15:22:27.324879Z","steps":["trace[1071780348] 'process raft request' (duration: 264.278082ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:27.324968Z","caller":"traceutil/trace.go:171","msg":"trace[748901330] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1336; }","duration":"194.945784ms","start":"2026-09-18T15:22:27.130016Z","end":"2026-09-18T15:22:27.324961Z","steps":["trace[748901330] 'agreement among raft nodes before linearized reading' (duration: 194.804222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:28.188389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.914866ms","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-09-18T15:22:28.188486Z","caller":"traceutil/trace.go:171","msg":"trace[191216494] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1340; }","duration":"109.10363ms","start":"2026-09-18T15:22:28.079366Z","end":"2026-09-18T15:22:28.18847Z","steps":["trace[191216494] 'count revisions from in-memory index tree' (duration: 108.776225ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:28.439252Z","caller":"traceutil/trace.go:171","msg":"trace[102225458] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"104.948952ms","start":"2026-09-18T15:22:28.334281Z","end":"2026-09-18T15:22:28.43923Z","steps":["trace[102225458] 'process raft request' (duration: 104.76975ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:33.665481Z","caller":"traceutil/trace.go:171","msg":"trace[429451277] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"140.072552ms","start":"2026-09-18T15:22:33.525391Z","end":"2026-09-18T15:22:33.665464Z","steps":["trace[429451277] 'process raft request' (duration: 139.94839ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:33.837798Z","caller":"traceutil/trace.go:171","msg":"trace[195653610] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"103.542223ms","start":"2026-09-18T15:22:33.734226Z","end":"2026-09-18T15:22:33.837768Z","steps":["trace[195653610] 'process raft request' (duration: 103.361691ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:34.658344Z","caller":"traceutil/trace.go:171","msg":"trace[98125748] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"332.784877ms","start":"2026-09-18T15:22:34.32554Z","end":"2026-09-18T15:22:34.658325Z","steps":["trace[98125748] 'read index received' (duration: 332.724897ms)","trace[98125748] 'applied index is now lower than readState.Index' (duration: 58.95µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:34.658471Z","caller":"traceutil/trace.go:171","msg":"trace[1703952523] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"554.078917ms","start":"2026-09-18T15:22:34.104362Z","end":"2026-09-18T15:22:34.658441Z","steps":["trace[1703952523] 'process raft request' (duration: 553.846734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:34.658612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.057281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T15:22:34.658647Z","caller":"traceutil/trace.go:171","msg":"trace[808339023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"333.147063ms","start":"2026-09-18T15:22:34.32549Z","end":"2026-09-18T15:22:34.658637Z","steps":["trace[808339023] 'agreement among raft nodes before linearized reading' (duration: 332.9302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:34.658636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:22:34.104333Z","time spent":"554.201119ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:22:34.658674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:22:34.32547Z","time spent":"333.198284ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T15:22:34.681416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.788282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-18T15:22:34.681451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.094472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-f624p\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-09-18T15:22:34.682119Z","caller":"traceutil/trace.go:171","msg":"trace[1244578641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"152.498873ms","start":"2026-09-18T15:22:34.529564Z","end":"2026-09-18T15:22:34.682063Z","steps":["trace[1244578641] 'agreement among raft nodes before linearized reading' (duration: 151.723591ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:34.682194Z","caller":"traceutil/trace.go:171","msg":"trace[289559979] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-f624p; range_end:; response_count:1; response_revision:1365; }","duration":"333.890775ms","start":"2026-09-18T15:22:34.348275Z","end":"2026-09-18T15:22:34.682166Z","steps":["trace[289559979] 'agreement among raft nodes before linearized reading' (duration: 332.994121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:22:34.682247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:22:34.34825Z","time spent":"333.983086ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4220,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-f624p\" "} {"level":"info","ts":"2026-09-18T15:22:34.772046Z","caller":"traceutil/trace.go:171","msg":"trace[146492523] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"109.270804ms","start":"2026-09-18T15:22:34.662717Z","end":"2026-09-18T15:22:34.771987Z","steps":["trace[146492523] 'process raft request' (duration: 82.961326ms)","trace[146492523] 'compare' (duration: 26.174566ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:22:34.887739Z","caller":"traceutil/trace.go:171","msg":"trace[459861555] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"200.06932ms","start":"2026-09-18T15:22:34.687649Z","end":"2026-09-18T15:22:34.887718Z","steps":["trace[459861555] 'process raft request' (duration: 199.950319ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:34.887901Z","caller":"traceutil/trace.go:171","msg":"trace[53667595] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"202.222595ms","start":"2026-09-18T15:22:34.685656Z","end":"2026-09-18T15:22:34.887879Z","steps":["trace[53667595] 'process raft request' (duration: 199.591634ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:22:41.04128Z","caller":"traceutil/trace.go:171","msg":"trace[1343508478] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"131.466951ms","start":"2026-09-18T15:22:40.909779Z","end":"2026-09-18T15:22:41.041246Z","steps":["trace[1343508478] 'process raft request' (duration: 124.05572ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:06.560581Z","caller":"traceutil/trace.go:171","msg":"trace[1419202859] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"135.4422ms","start":"2026-09-18T15:23:06.42509Z","end":"2026-09-18T15:23:06.560532Z","steps":["trace[1419202859] 'process raft request' (duration: 99.603951ms)","trace[1419202859] 'compare' (duration: 35.511205ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:07.51044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.543424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:23:07.510564Z","caller":"traceutil/trace.go:171","msg":"trace[117208967] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"721.812198ms","start":"2026-09-18T15:23:06.78873Z","end":"2026-09-18T15:23:07.510542Z","steps":["trace[117208967] 'read index received' (duration: 38.389332ms)","trace[117208967] 'applied index is now lower than readState.Index' (duration: 683.420476ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:07.510638Z","caller":"traceutil/trace.go:171","msg":"trace[717762894] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"942.705329ms","start":"2026-09-18T15:23:06.567899Z","end":"2026-09-18T15:23:07.510604Z","steps":["trace[717762894] 'process raft request' (duration: 259.257873ms)","trace[717762894] 'compare' (duration: 681.742853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:07.510682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.944859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34542bfe-efa6-4a94-be0d-906ecefa5cda\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-18T15:23:07.51074Z","caller":"traceutil/trace.go:171","msg":"trace[227101083] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34542bfe-efa6-4a94-be0d-906ecefa5cda; range_end:; response_count:1; response_revision:1906; }","duration":"722.037811ms","start":"2026-09-18T15:23:06.788688Z","end":"2026-09-18T15:23:07.510726Z","steps":["trace[227101083] 'agreement among raft nodes before linearized reading' (duration: 721.94126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.510822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.78867Z","time spent":"722.137912ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34542bfe-efa6-4a94-be0d-906ecefa5cda\" "} {"level":"warn","ts":"2026-09-18T15:23:07.510852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.567873Z","time spent":"942.854901ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:07.700547Z","caller":"traceutil/trace.go:171","msg":"trace[1154498301] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"189.918257ms","start":"2026-09-18T15:23:07.510596Z","end":"2026-09-18T15:23:07.700514Z","steps":["trace[1154498301] 'read index received' (duration: 154.860061ms)","trace[1154498301] 'applied index is now lower than readState.Index' (duration: 35.056216ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:07.700916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.061135ms","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-09-18T15:23:07.700956Z","caller":"traceutil/trace.go:171","msg":"trace[1938702488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"863.197107ms","start":"2026-09-18T15:23:06.837747Z","end":"2026-09-18T15:23:07.700944Z","steps":["trace[1938702488] 'agreement among raft nodes before linearized reading' (duration: 862.950973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.700939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.337843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:23:07.701066Z","caller":"traceutil/trace.go:171","msg":"trace[1948649404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"739.498315ms","start":"2026-09-18T15:23:06.961532Z","end":"2026-09-18T15:23:07.70103Z","steps":["trace[1948649404] 'agreement among raft nodes before linearized reading' (duration: 739.051468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.701085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.29332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-09-18T15:23:07.701108Z","caller":"traceutil/trace.go:171","msg":"trace[847561523] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1907; }","duration":"186.379981ms","start":"2026-09-18T15:23:07.514722Z","end":"2026-09-18T15:23:07.701102Z","steps":["trace[847561523] 'agreement among raft nodes before linearized reading' (duration: 186.29984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.701122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.961512Z","time spent":"739.586446ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T15:23:07.701118Z","caller":"traceutil/trace.go:171","msg":"trace[655833543] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"884.142457ms","start":"2026-09-18T15:23:06.816926Z","end":"2026-09-18T15:23:07.701069Z","steps":["trace[655833543] 'process raft request' (duration: 848.487632ms)","trace[655833543] 'compare' (duration: 34.947736ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:07.701276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.81689Z","time spent":"884.306539ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:07.700993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.83773Z","time spent":"863.255528ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T15:23:07.701273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.266941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T15:23:07.701504Z","caller":"traceutil/trace.go:171","msg":"trace[429429075] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"855.525354ms","start":"2026-09-18T15:23:06.845938Z","end":"2026-09-18T15:23:07.701463Z","steps":["trace[429429075] 'agreement among raft nodes before linearized reading' (duration: 854.712543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.701589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:06.845923Z","time spent":"855.651986ms","remote":"127.0.0.1:57462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-18T15:23:07.70168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.892254ms","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-09-18T15:23:07.701733Z","caller":"traceutil/trace.go:171","msg":"trace[1165820234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"379.000854ms","start":"2026-09-18T15:23:07.322704Z","end":"2026-09-18T15:23:07.701705Z","steps":["trace[1165820234] 'agreement among raft nodes before linearized reading' (duration: 378.865033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.701789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.322673Z","time spent":"379.105876ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:23:07.702021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.423625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:07.702068Z","caller":"traceutil/trace.go:171","msg":"trace[544268325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1907; }","duration":"695.498256ms","start":"2026-09-18T15:23:07.006557Z","end":"2026-09-18T15:23:07.702056Z","steps":["trace[544268325] 'agreement among raft nodes before linearized reading' (duration: 695.423755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:07.702092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.006542Z","time spent":"695.543316ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:23:08.33617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9465898691501434209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:23:08.44457Z","caller":"traceutil/trace.go:171","msg":"trace[820003351] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"735.461398ms","start":"2026-09-18T15:23:07.709086Z","end":"2026-09-18T15:23:08.444547Z","steps":["trace[820003351] 'process raft request' (duration: 735.281125ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:08.444706Z","caller":"traceutil/trace.go:171","msg":"trace[1158774784] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"735.041281ms","start":"2026-09-18T15:23:07.709635Z","end":"2026-09-18T15:23:08.444676Z","steps":["trace[1158774784] 'process raft request' (duration: 734.846419ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:08.444727Z","caller":"traceutil/trace.go:171","msg":"trace[145223949] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"734.92551ms","start":"2026-09-18T15:23:07.709711Z","end":"2026-09-18T15:23:08.444636Z","steps":["trace[145223949] 'process raft request' (duration: 734.806148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:08.444881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.709616Z","time spent":"735.195283ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:08.444961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.7097Z","time spent":"735.167113ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:08.445034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.709062Z","time spent":"735.65326ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:08.444612Z","caller":"traceutil/trace.go:171","msg":"trace[1634081675] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"731.228571ms","start":"2026-09-18T15:23:07.713373Z","end":"2026-09-18T15:23:08.444601Z","steps":["trace[1634081675] 'process raft request' (duration: 731.179551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:08.445183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.713358Z","time spent":"731.792648ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:08.486388Z","caller":"traceutil/trace.go:171","msg":"trace[1932865560] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"632.125399ms","start":"2026-09-18T15:23:07.85424Z","end":"2026-09-18T15:23:08.486365Z","steps":["trace[1932865560] 'process raft request' (duration: 631.926446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:08.486527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.854206Z","time spent":"632.250061ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:08.486608Z","caller":"traceutil/trace.go:171","msg":"trace[704625068] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1982; }","duration":"650.708997ms","start":"2026-09-18T15:23:07.835871Z","end":"2026-09-18T15:23:08.48658Z","steps":["trace[704625068] 'read index received' (duration: 608.554728ms)","trace[704625068] 'applied index is now lower than readState.Index' (duration: 42.152659ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:08.48689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.998681ms","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-09-18T15:23:08.486948Z","caller":"traceutil/trace.go:171","msg":"trace[1677200121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1914; }","duration":"651.124412ms","start":"2026-09-18T15:23:07.835808Z","end":"2026-09-18T15:23:08.486932Z","steps":["trace[1677200121] 'agreement among raft nodes before linearized reading' (duration: 650.879389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:08.487079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:07.83575Z","time spent":"651.312064ms","remote":"127.0.0.1:34136","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":"info","ts":"2026-09-18T15:23:13.947268Z","caller":"traceutil/trace.go:171","msg":"trace[1182723315] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2042; }","duration":"206.338517ms","start":"2026-09-18T15:23:13.740892Z","end":"2026-09-18T15:23:13.94723Z","steps":["trace[1182723315] 'read index received' (duration: 206.025113ms)","trace[1182723315] 'applied index is now lower than readState.Index' (duration: 311.744µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:13.947456Z","caller":"traceutil/trace.go:171","msg":"trace[1041429295] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"223.093174ms","start":"2026-09-18T15:23:13.724323Z","end":"2026-09-18T15:23:13.947416Z","steps":["trace[1041429295] 'process raft request' (duration: 222.681879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:13.947633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.431336ms","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-09-18T15:23:13.947721Z","caller":"traceutil/trace.go:171","msg":"trace[1900617188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1968; }","duration":"199.546588ms","start":"2026-09-18T15:23:13.748127Z","end":"2026-09-18T15:23:13.947674Z","steps":["trace[1900617188] 'agreement among raft nodes before linearized reading' (duration: 199.315775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:13.947731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.770432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T15:23:13.947828Z","caller":"traceutil/trace.go:171","msg":"trace[325058202] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"206.959405ms","start":"2026-09-18T15:23:13.740849Z","end":"2026-09-18T15:23:13.947809Z","steps":["trace[325058202] 'agreement among raft nodes before linearized reading' (duration: 206.56871ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:20.855786Z","caller":"traceutil/trace.go:171","msg":"trace[1467826523] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"359.574072ms","start":"2026-09-18T15:23:20.496185Z","end":"2026-09-18T15:23:20.855759Z","steps":["trace[1467826523] 'process raft request' (duration: 333.741894ms)","trace[1467826523] 'compare' (duration: 25.259901ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:20.855894Z","caller":"traceutil/trace.go:171","msg":"trace[382318214] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"310.932593ms","start":"2026-09-18T15:23:20.544933Z","end":"2026-09-18T15:23:20.855866Z","steps":["trace[382318214] 'process raft request' (duration: 310.763731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:20.856583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:20.544908Z","time spent":"311.525671ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:20.856992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:20.496161Z","time spent":"360.134979ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:20.865068Z","caller":"traceutil/trace.go:171","msg":"trace[1349693356] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"312.892909ms","start":"2026-09-18T15:23:20.552151Z","end":"2026-09-18T15:23:20.865044Z","steps":["trace[1349693356] 'process raft request' (duration: 312.695366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:20.865241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:20.552119Z","time spent":"313.02827ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:20.86556Z","caller":"traceutil/trace.go:171","msg":"trace[1480316113] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"184.253623ms","start":"2026-09-18T15:23:20.681277Z","end":"2026-09-18T15:23:20.865531Z","steps":["trace[1480316113] 'process raft request' (duration: 183.718977ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:20.866053Z","caller":"traceutil/trace.go:171","msg":"trace[835530621] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2093; }","duration":"186.847845ms","start":"2026-09-18T15:23:20.679192Z","end":"2026-09-18T15:23:20.86604Z","steps":["trace[835530621] 'read index received' (duration: 150.657336ms)","trace[835530621] 'applied index is now lower than readState.Index' (duration: 36.189439ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:20.866252Z","caller":"traceutil/trace.go:171","msg":"trace[1296722764] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"184.81076ms","start":"2026-09-18T15:23:20.681428Z","end":"2026-09-18T15:23:20.866239Z","steps":["trace[1296722764] 'process raft request' (duration: 183.98966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:20.866527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.307742ms","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-09-18T15:23:20.866565Z","caller":"traceutil/trace.go:171","msg":"trace[1641735732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2021; }","duration":"187.403883ms","start":"2026-09-18T15:23:20.679152Z","end":"2026-09-18T15:23:20.866556Z","steps":["trace[1641735732] 'agreement among raft nodes before linearized reading' (duration: 186.943077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.504733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9465898691501434490,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:23:24.983849Z","caller":"traceutil/trace.go:171","msg":"trace[1677609912] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"979.463137ms","start":"2026-09-18T15:23:24.004366Z","end":"2026-09-18T15:23:24.983829Z","steps":["trace[1677609912] 'read index received' (duration: 979.377736ms)","trace[1677609912] 'applied index is now lower than readState.Index' (duration: 84.521µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:24.983893Z","caller":"traceutil/trace.go:171","msg":"trace[1337780052] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"999.371338ms","start":"2026-09-18T15:23:23.984505Z","end":"2026-09-18T15:23:24.983876Z","steps":["trace[1337780052] 'process raft request' (duration: 999.167565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.984005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.626239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:24.98406Z","caller":"traceutil/trace.go:171","msg":"trace[1337946433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2046; }","duration":"979.746772ms","start":"2026-09-18T15:23:24.004305Z","end":"2026-09-18T15:23:24.984052Z","steps":["trace[1337946433] 'agreement among raft nodes before linearized reading' (duration: 979.617399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.984098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.004277Z","time spent":"979.809852ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:23:24.984177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:23.984449Z","time spent":"999.597042ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:23:24.990114Z","caller":"traceutil/trace.go:171","msg":"trace[1042827880] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"120.096998ms","start":"2026-09-18T15:23:24.870001Z","end":"2026-09-18T15:23:24.990098Z","steps":["trace[1042827880] 'process raft request' (duration: 119.969387ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:24.99041Z","caller":"traceutil/trace.go:171","msg":"trace[571525966] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"104.003096ms","start":"2026-09-18T15:23:24.886391Z","end":"2026-09-18T15:23:24.990394Z","steps":["trace[571525966] 'process raft request' (duration: 103.675052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.990624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.634624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:24.990656Z","caller":"traceutil/trace.go:171","msg":"trace[1523644187] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2049; }","duration":"437.715435ms","start":"2026-09-18T15:23:24.55293Z","end":"2026-09-18T15:23:24.990645Z","steps":["trace[1523644187] 'agreement among raft nodes before linearized reading' (duration: 437.634064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.990678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.552906Z","time spent":"437.763536ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:23:24.990679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.8117ms","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-09-18T15:23:24.990736Z","caller":"traceutil/trace.go:171","msg":"trace[1960037230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2049; }","duration":"423.895752ms","start":"2026-09-18T15:23:24.566825Z","end":"2026-09-18T15:23:24.99072Z","steps":["trace[1960037230] 'agreement among raft nodes before linearized reading' (duration: 423.73147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.990771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.566807Z","time spent":"423.954892ms","remote":"127.0.0.1:34136","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-09-18T15:23:24.990785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.483926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"warn","ts":"2026-09-18T15:23:24.990837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.603252ms","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-09-18T15:23:24.990833Z","caller":"traceutil/trace.go:171","msg":"trace[489729426] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2049; }","duration":"943.605508ms","start":"2026-09-18T15:23:24.047215Z","end":"2026-09-18T15:23:24.990821Z","steps":["trace[489729426] 'agreement among raft nodes before linearized reading' (duration: 943.466196ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:24.990861Z","caller":"traceutil/trace.go:171","msg":"trace[889565615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2049; }","duration":"984.653442ms","start":"2026-09-18T15:23:24.0062Z","end":"2026-09-18T15:23:24.990854Z","steps":["trace[889565615] 'agreement among raft nodes before linearized reading' (duration: 984.587591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.99087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.047195Z","time spent":"943.669228ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12012,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-18T15:23:24.990884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.006184Z","time spent":"984.694752ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:23:24.990891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.336156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:23:24.990916Z","caller":"traceutil/trace.go:171","msg":"trace[2050375994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2049; }","duration":"103.378717ms","start":"2026-09-18T15:23:24.887529Z","end":"2026-09-18T15:23:24.990908Z","steps":["trace[2050375994] 'agreement among raft nodes before linearized reading' (duration: 103.307666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.99102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.934113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T15:23:24.991026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.939361ms","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-09-18T15:23:24.991044Z","caller":"traceutil/trace.go:171","msg":"trace[1296772308] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2049; }","duration":"525.994274ms","start":"2026-09-18T15:23:24.46504Z","end":"2026-09-18T15:23:24.991034Z","steps":["trace[1296772308] 'agreement among raft nodes before linearized reading' (duration: 525.955263ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:24.991048Z","caller":"traceutil/trace.go:171","msg":"trace[1225491659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2049; }","duration":"109.993841ms","start":"2026-09-18T15:23:24.881046Z","end":"2026-09-18T15:23:24.99104Z","steps":["trace[1225491659] 'agreement among raft nodes before linearized reading' (duration: 109.92683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.991063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.465019Z","time spent":"526.039605ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:23:24.991113Z","caller":"traceutil/trace.go:171","msg":"trace[1057054226] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"104.648394ms","start":"2026-09-18T15:23:24.886456Z","end":"2026-09-18T15:23:24.991105Z","steps":["trace[1057054226] 'process raft request' (duration: 103.884334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.991211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.076416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T15:23:24.99124Z","caller":"traceutil/trace.go:171","msg":"trace[1559710733] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2049; }","duration":"941.122286ms","start":"2026-09-18T15:23:24.050099Z","end":"2026-09-18T15:23:24.991221Z","steps":["trace[1559710733] 'agreement among raft nodes before linearized reading' (duration: 941.061076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:24.991252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:24.050084Z","time spent":"941.165087ms","remote":"127.0.0.1:57462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-18T15:23:42.093618Z","caller":"traceutil/trace.go:171","msg":"trace[1125844828] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"149.416797ms","start":"2026-09-18T15:23:41.944183Z","end":"2026-09-18T15:23:42.0936Z","steps":["trace[1125844828] 'process raft request' (duration: 149.011682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:47.587761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.171298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:23:47.58788Z","caller":"traceutil/trace.go:171","msg":"trace[807398716] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"348.060441ms","start":"2026-09-18T15:23:47.239799Z","end":"2026-09-18T15:23:47.587859Z","steps":["trace[807398716] 'read index received' (duration: 189.740431ms)","trace[807398716] 'applied index is now lower than readState.Index' (duration: 158.31835ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:47.587915Z","caller":"traceutil/trace.go:171","msg":"trace[1781772922] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"354.646448ms","start":"2026-09-18T15:23:47.233242Z","end":"2026-09-18T15:23:47.587889Z","steps":["trace[1781772922] 'process raft request' (duration: 196.269628ms)","trace[1781772922] 'compare' (duration: 157.53493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:47.588085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:47.233231Z","time spent":"354.74539ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:47.588096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.200612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T15:23:47.588135Z","caller":"traceutil/trace.go:171","msg":"trace[1808159051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"348.266663ms","start":"2026-09-18T15:23:47.239856Z","end":"2026-09-18T15:23:47.588122Z","steps":["trace[1808159051] 'agreement among raft nodes before linearized reading' (duration: 348.111461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:47.588165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:47.239839Z","time spent":"348.317903ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T15:23:47.588139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.320754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T15:23:47.588229Z","caller":"traceutil/trace.go:171","msg":"trace[352420765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"348.436405ms","start":"2026-09-18T15:23:47.239773Z","end":"2026-09-18T15:23:47.58821Z","steps":["trace[352420765] 'agreement among raft nodes before linearized reading' (duration: 348.165192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:47.588287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:47.239762Z","time spent":"348.515065ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T15:23:47.797126Z","caller":"traceutil/trace.go:171","msg":"trace[395112948] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"204.472334ms","start":"2026-09-18T15:23:47.592628Z","end":"2026-09-18T15:23:47.7971Z","steps":["trace[395112948] 'process raft request' (duration: 204.420234ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:47.797185Z","caller":"traceutil/trace.go:171","msg":"trace[8513108] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"204.587756ms","start":"2026-09-18T15:23:47.59257Z","end":"2026-09-18T15:23:47.797157Z","steps":["trace[8513108] 'process raft request' (duration: 161.449476ms)","trace[8513108] 'compare' (duration: 42.860947ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:49.268582Z","caller":"traceutil/trace.go:171","msg":"trace[383126256] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2265; }","duration":"122.560361ms","start":"2026-09-18T15:23:49.145985Z","end":"2026-09-18T15:23:49.268546Z","steps":["trace[383126256] 'read index received' (duration: 43.875016ms)","trace[383126256] 'applied index is now lower than readState.Index' (duration: 78.683875ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:49.268633Z","caller":"traceutil/trace.go:171","msg":"trace[332646062] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"135.061497ms","start":"2026-09-18T15:23:49.133518Z","end":"2026-09-18T15:23:49.26858Z","steps":["trace[332646062] 'process raft request' (duration: 105.118145ms)","trace[332646062] 'compare' (duration: 29.74346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:49.268883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.875114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T15:23:49.268913Z","caller":"traceutil/trace.go:171","msg":"trace[1251903870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2185; }","duration":"122.958006ms","start":"2026-09-18T15:23:49.145947Z","end":"2026-09-18T15:23:49.268905Z","steps":["trace[1251903870] 'agreement among raft nodes before linearized reading' (duration: 122.760964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:49.269134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.801718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T15:23:49.269246Z","caller":"traceutil/trace.go:171","msg":"trace[683096688] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"113.97926ms","start":"2026-09-18T15:23:49.155243Z","end":"2026-09-18T15:23:49.269223Z","steps":["trace[683096688] 'agreement among raft nodes before linearized reading' (duration: 113.629865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:49.527597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.828157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:23:49.527791Z","caller":"traceutil/trace.go:171","msg":"trace[591848340] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"253.329438ms","start":"2026-09-18T15:23:49.274443Z","end":"2026-09-18T15:23:49.527772Z","steps":["trace[591848340] 'process raft request' (duration: 253.257277ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:49.527866Z","caller":"traceutil/trace.go:171","msg":"trace[1889939289] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"254.038866ms","start":"2026-09-18T15:23:49.273783Z","end":"2026-09-18T15:23:49.527822Z","steps":["trace[1889939289] 'process raft request' (duration: 123.887266ms)","trace[1889939289] 'compare' (duration: 129.687445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:50.12014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.137625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:23:50.120226Z","caller":"traceutil/trace.go:171","msg":"trace[250346317] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"319.16012ms","start":"2026-09-18T15:23:49.801053Z","end":"2026-09-18T15:23:50.120214Z","steps":["trace[250346317] 'read index received' (duration: 27.281µs)","trace[250346317] 'applied index is now lower than readState.Index' (duration: 319.131729ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:50.120375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.301241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T15:23:50.120441Z","caller":"traceutil/trace.go:171","msg":"trace[1168821181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2188; }","duration":"319.400692ms","start":"2026-09-18T15:23:49.801025Z","end":"2026-09-18T15:23:50.120426Z","steps":["trace[1168821181] 'agreement among raft nodes before linearized reading' (duration: 319.24569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.120468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:49.801011Z","time spent":"319.450213ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T15:23:50.120375Z","caller":"traceutil/trace.go:171","msg":"trace[1941850743] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"526.747099ms","start":"2026-09-18T15:23:49.593597Z","end":"2026-09-18T15:23:50.120344Z","steps":["trace[1941850743] 'process raft request' (duration: 146.326361ms)","trace[1941850743] 'compare' (duration: 379.952063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:50.120687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.721809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T15:23:50.120712Z","caller":"traceutil/trace.go:171","msg":"trace[375734710] range","detail":"{range_begin:/registry/masterleases/162.253.55.124; range_end:; response_count:1; response_revision:2188; }","duration":"167.77864ms","start":"2026-09-18T15:23:49.952927Z","end":"2026-09-18T15:23:50.120706Z","steps":["trace[375734710] 'agreement among raft nodes before linearized reading' (duration: 167.725079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.120804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:49.593586Z","time spent":"527.052374ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:23:50.12084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.816446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:50.121183Z","caller":"traceutil/trace.go:171","msg":"trace[341938143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"115.171591ms","start":"2026-09-18T15:23:50.005991Z","end":"2026-09-18T15:23:50.121163Z","steps":["trace[341938143] 'agreement among raft nodes before linearized reading' (duration: 114.835207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.121257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.202552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T15:23:50.12134Z","caller":"traceutil/trace.go:171","msg":"trace[1186665750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2188; }","duration":"320.312873ms","start":"2026-09-18T15:23:49.80102Z","end":"2026-09-18T15:23:50.121333Z","steps":["trace[1186665750] 'agreement among raft nodes before linearized reading' (duration: 319.275231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.12136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:49.801002Z","time spent":"320.349473ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T15:23:50.348052Z","caller":"traceutil/trace.go:171","msg":"trace[107652462] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2272; }","duration":"225.535246ms","start":"2026-09-18T15:23:50.12247Z","end":"2026-09-18T15:23:50.348005Z","steps":["trace[107652462] 'read index received' (duration: 225.512336ms)","trace[107652462] 'applied index is now lower than readState.Index' (duration: 18.91µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:50.348421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.9027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:50.348557Z","caller":"traceutil/trace.go:171","msg":"trace[771561851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"226.083842ms","start":"2026-09-18T15:23:50.122453Z","end":"2026-09-18T15:23:50.348537Z","steps":["trace[771561851] 'agreement among raft nodes before linearized reading' (duration: 225.680397ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:50.392531Z","caller":"traceutil/trace.go:171","msg":"trace[1166087129] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"267.480348ms","start":"2026-09-18T15:23:50.125021Z","end":"2026-09-18T15:23:50.392502Z","steps":["trace[1166087129] 'process raft request' (duration: 267.364747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.392581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.14623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-09-18T15:23:50.392631Z","caller":"traceutil/trace.go:171","msg":"trace[1187817203] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"267.74078ms","start":"2026-09-18T15:23:50.124853Z","end":"2026-09-18T15:23:50.392594Z","steps":["trace[1187817203] 'process raft request' (duration: 267.347386ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:50.39268Z","caller":"traceutil/trace.go:171","msg":"trace[1127279788] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2190; }","duration":"149.318622ms","start":"2026-09-18T15:23:50.243344Z","end":"2026-09-18T15:23:50.392663Z","steps":["trace[1127279788] 'agreement among raft nodes before linearized reading' (duration: 149.077599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.392591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.535347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T15:23:50.39284Z","caller":"traceutil/trace.go:171","msg":"trace[1991783077] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"116.81956ms","start":"2026-09-18T15:23:50.27601Z","end":"2026-09-18T15:23:50.392829Z","steps":["trace[1991783077] 'agreement among raft nodes before linearized reading' (duration: 116.507526ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:50.65707Z","caller":"traceutil/trace.go:171","msg":"trace[2130765608] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2274; }","duration":"254.108571ms","start":"2026-09-18T15:23:50.402931Z","end":"2026-09-18T15:23:50.657039Z","steps":["trace[2130765608] 'read index received' (duration: 246.293119ms)","trace[2130765608] 'applied index is now lower than readState.Index' (duration: 7.812952ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:23:50.657203Z","caller":"traceutil/trace.go:171","msg":"trace[1980466598] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"307.317036ms","start":"2026-09-18T15:23:50.349856Z","end":"2026-09-18T15:23:50.657173Z","steps":["trace[1980466598] 'process raft request' (duration: 299.386852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.657259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.273123ms","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-09-18T15:23:50.657329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.224509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:23:50.65737Z","caller":"traceutil/trace.go:171","msg":"trace[1795059013] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2191; }","duration":"254.449465ms","start":"2026-09-18T15:23:50.402905Z","end":"2026-09-18T15:23:50.657355Z","steps":["trace[1795059013] 'agreement among raft nodes before linearized reading' (duration: 254.260023ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:50.657376Z","caller":"traceutil/trace.go:171","msg":"trace[857983825] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2191; }","duration":"159.37514ms","start":"2026-09-18T15:23:50.497986Z","end":"2026-09-18T15:23:50.657361Z","steps":["trace[857983825] 'agreement among raft nodes before linearized reading' (duration: 159.221648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:50.657482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:50.349813Z","time spent":"307.479377ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:24:15.837446Z","caller":"traceutil/trace.go:171","msg":"trace[1708063743] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"128.729082ms","start":"2026-09-18T15:24:15.708661Z","end":"2026-09-18T15:24:15.83739Z","steps":["trace[1708063743] 'process raft request' (duration: 101.360576ms)","trace[1708063743] 'compare' (duration: 27.143953ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:24:48.160054Z","caller":"traceutil/trace.go:171","msg":"trace[866811061] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"101.670323ms","start":"2026-09-18T15:24:48.058362Z","end":"2026-09-18T15:24:48.160033Z","steps":["trace[866811061] 'process raft request' (duration: 101.555812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:23.925291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.44209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:25:23.925447Z","caller":"traceutil/trace.go:171","msg":"trace[1183653696] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"378.473965ms","start":"2026-09-18T15:25:23.546957Z","end":"2026-09-18T15:25:23.925431Z","steps":["trace[1183653696] 'process raft request' (duration: 378.433774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:23.925499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:23.546946Z","time spent":"378.528566ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:25:23.925781Z","caller":"traceutil/trace.go:171","msg":"trace[661197451] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"384.721541ms","start":"2026-09-18T15:25:23.541044Z","end":"2026-09-18T15:25:23.925766Z","steps":["trace[661197451] 'compare' (duration: 375.353636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:23.92584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:23.541036Z","time spent":"384.779463ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:25:23.928087Z","caller":"traceutil/trace.go:171","msg":"trace[952271122] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"308.146281ms","start":"2026-09-18T15:25:23.619925Z","end":"2026-09-18T15:25:23.928071Z","steps":["trace[952271122] 'process raft request' (duration: 308.020037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:23.928197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:23.619901Z","time spent":"308.240046ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:25:23.928186Z","caller":"traceutil/trace.go:171","msg":"trace[872614761] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2885; }","duration":"169.897709ms","start":"2026-09-18T15:25:23.75827Z","end":"2026-09-18T15:25:23.928168Z","steps":["trace[872614761] 'read index received' (duration: 159.355173ms)","trace[872614761] 'applied index is now lower than readState.Index' (duration: 10.541105ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:23.928314Z","caller":"traceutil/trace.go:171","msg":"trace[1392435164] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"210.69008ms","start":"2026-09-18T15:25:23.717618Z","end":"2026-09-18T15:25:23.928308Z","steps":["trace[1392435164] 'process raft request' (duration: 210.4268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:23.928333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.054954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T15:25:23.928362Z","caller":"traceutil/trace.go:171","msg":"trace[1737357803] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"170.118346ms","start":"2026-09-18T15:25:23.758235Z","end":"2026-09-18T15:25:23.928354Z","steps":["trace[1737357803] 'agreement among raft nodes before linearized reading' (duration: 169.991832ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:54.4853Z","caller":"traceutil/trace.go:171","msg":"trace[1192175177] transaction","detail":"{read_only:false; response_revision:2953; number_of_response:1; }","duration":"386.514423ms","start":"2026-09-18T15:25:54.098729Z","end":"2026-09-18T15:25:54.485243Z","steps":["trace[1192175177] 'process raft request' (duration: 295.184468ms)","trace[1192175177] 'compare' (duration: 90.966233ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:25:54.485439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:54.098706Z","time spent":"386.663948ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:25:54.4854Z","caller":"traceutil/trace.go:171","msg":"trace[628131927] transaction","detail":"{read_only:false; response_revision:2954; number_of_response:1; }","duration":"376.340878ms","start":"2026-09-18T15:25:54.109029Z","end":"2026-09-18T15:25:54.485369Z","steps":["trace[628131927] 'process raft request' (duration: 376.180173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:54.485723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:54.109011Z","time spent":"376.596756ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:00.019003Z","caller":"traceutil/trace.go:171","msg":"trace[879856483] linearizableReadLoop","detail":"{readStateIndex:3094; appliedIndex:3093; }","duration":"258.402238ms","start":"2026-09-18T15:25:59.76058Z","end":"2026-09-18T15:26:00.018982Z","steps":["trace[879856483] 'read index received' (duration: 258.211612ms)","trace[879856483] 'applied index is now lower than readState.Index' (duration: 189.946µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:00.019052Z","caller":"traceutil/trace.go:171","msg":"trace[1315929156] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"342.816351ms","start":"2026-09-18T15:25:59.676208Z","end":"2026-09-18T15:26:00.019024Z","steps":["trace[1315929156] 'process raft request' (duration: 342.632726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:00.019467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:59.67618Z","time spent":"343.228433ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:26:00.019225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.623755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T15:26:00.019679Z","caller":"traceutil/trace.go:171","msg":"trace[1930274861] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2983; }","duration":"259.108209ms","start":"2026-09-18T15:25:59.760539Z","end":"2026-09-18T15:26:00.019647Z","steps":["trace[1930274861] 'agreement among raft nodes before linearized reading' (duration: 258.570923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:00.194486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.601917ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-18T15:26:00.19468Z","caller":"traceutil/trace.go:171","msg":"trace[2006893739] transaction","detail":"{read_only:false; response_revision:2984; number_of_response:1; }","duration":"105.978013ms","start":"2026-09-18T15:26:00.088689Z","end":"2026-09-18T15:26:00.194667Z","steps":["trace[2006893739] 'process raft request' (duration: 105.8792ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:00.195237Z","caller":"traceutil/trace.go:171","msg":"trace[563852465] linearizableReadLoop","detail":"{readStateIndex:3095; appliedIndex:3094; }","duration":"171.970867ms","start":"2026-09-18T15:26:00.023254Z","end":"2026-09-18T15:26:00.195225Z","steps":["trace[563852465] 'read index received' (duration: 58.552695ms)","trace[563852465] 'applied index is now lower than readState.Index' (duration: 113.414592ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:00.195405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.138621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-09-18T15:26:00.195439Z","caller":"traceutil/trace.go:171","msg":"trace[188646176] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2984; }","duration":"172.193423ms","start":"2026-09-18T15:26:00.023237Z","end":"2026-09-18T15:26:00.19543Z","steps":["trace[188646176] 'agreement among raft nodes before linearized reading' (duration: 172.033518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:29:42.348798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.447613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-18T15:29:42.348927Z","caller":"traceutil/trace.go:171","msg":"trace[1489185127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4341; }","duration":"146.612537ms","start":"2026-09-18T15:29:42.202294Z","end":"2026-09-18T15:29:42.348906Z","steps":["trace[1489185127] 'range keys from in-memory index tree' (duration: 146.242021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:29:42.348805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.031899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:29:42.349136Z","caller":"traceutil/trace.go:171","msg":"trace[292439861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4341; }","duration":"132.369135ms","start":"2026-09-18T15:29:42.216712Z","end":"2026-09-18T15:29:42.349081Z","steps":["trace[292439861] 'range keys from in-memory index tree' (duration: 131.663833ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:29:49.295628Z","caller":"traceutil/trace.go:171","msg":"trace[2118094948] linearizableReadLoop","detail":"{readStateIndex:4974; appliedIndex:4973; }","duration":"109.174861ms","start":"2026-09-18T15:29:49.186437Z","end":"2026-09-18T15:29:49.295611Z","steps":["trace[2118094948] 'read index received' (duration: 108.979508ms)","trace[2118094948] 'applied index is now lower than readState.Index' (duration: 194.453µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:29:49.295782Z","caller":"traceutil/trace.go:171","msg":"trace[64371428] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"125.875561ms","start":"2026-09-18T15:29:49.169899Z","end":"2026-09-18T15:29:49.295774Z","steps":["trace[64371428] 'process raft request' (duration: 125.526945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:29:49.295994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.540647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f063b11a-e618-4acd-a014-d2cfcc8c6f00\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-18T15:29:49.296026Z","caller":"traceutil/trace.go:171","msg":"trace[237691383] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f063b11a-e618-4acd-a014-d2cfcc8c6f00; range_end:; response_count:1; response_revision:4790; }","duration":"109.610457ms","start":"2026-09-18T15:29:49.186407Z","end":"2026-09-18T15:29:49.296018Z","steps":["trace[237691383] 'agreement among raft nodes before linearized reading' (duration: 109.471475ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:29:54.616204Z","caller":"traceutil/trace.go:171","msg":"trace[495259621] linearizableReadLoop","detail":"{readStateIndex:5094; appliedIndex:5093; }","duration":"159.757701ms","start":"2026-09-18T15:29:54.456427Z","end":"2026-09-18T15:29:54.616184Z","steps":["trace[495259621] 'read index received' (duration: 120.830837ms)","trace[495259621] 'applied index is now lower than readState.Index' (duration: 38.925634ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:29:54.616562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.352151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:29:54.616645Z","caller":"traceutil/trace.go:171","msg":"trace[464087944] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4907; }","duration":"152.468904ms","start":"2026-09-18T15:29:54.464165Z","end":"2026-09-18T15:29:54.616634Z","steps":["trace[464087944] 'agreement among raft nodes before linearized reading' (duration: 152.355792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:29:54.616781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.84007ms","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-09-18T15:29:54.61685Z","caller":"traceutil/trace.go:171","msg":"trace[708167552] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"184.503528ms","start":"2026-09-18T15:29:54.432308Z","end":"2026-09-18T15:29:54.616812Z","steps":["trace[708167552] 'process raft request' (duration: 144.940892ms)","trace[708167552] 'compare' (duration: 38.799883ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:29:54.616899Z","caller":"traceutil/trace.go:171","msg":"trace[623966147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4907; }","duration":"173.085475ms","start":"2026-09-18T15:29:54.443771Z","end":"2026-09-18T15:29:54.616856Z","steps":["trace[623966147] 'agreement among raft nodes before linearized reading' (duration: 172.723689ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:29:55.642767Z","caller":"traceutil/trace.go:171","msg":"trace[1552932264] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"236.981694ms","start":"2026-09-18T15:29:55.405757Z","end":"2026-09-18T15:29:55.642738Z","steps":["trace[1552932264] 'process raft request' (duration: 236.71687ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:29:55.643341Z","caller":"traceutil/trace.go:171","msg":"trace[895445943] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"178.033011ms","start":"2026-09-18T15:29:55.465273Z","end":"2026-09-18T15:29:55.643306Z","steps":["trace[895445943] 'process raft request' (duration: 177.775876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:01.548281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.278966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:30:01.548411Z","caller":"traceutil/trace.go:171","msg":"trace[690087566] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"414.978196ms","start":"2026-09-18T15:30:01.133425Z","end":"2026-09-18T15:30:01.548404Z","steps":["trace[690087566] 'process raft request' (duration: 414.931115ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:01.548408Z","caller":"traceutil/trace.go:171","msg":"trace[67730693] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"617.767831ms","start":"2026-09-18T15:30:00.930622Z","end":"2026-09-18T15:30:01.548389Z","steps":["trace[67730693] 'process raft request' (duration: 225.300442ms)","trace[67730693] 'compare' (duration: 391.950071ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:30:01.548989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:30:00.930603Z","time spent":"618.354971ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:30:01.54845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:30:01.133407Z","time spent":"415.027936ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:30:01.548838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.572067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:30:01.549388Z","caller":"traceutil/trace.go:171","msg":"trace[750520041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4974; }","duration":"545.121605ms","start":"2026-09-18T15:30:01.004258Z","end":"2026-09-18T15:30:01.549379Z","steps":["trace[750520041] 'agreement among raft nodes before linearized reading' (duration: 544.553405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:01.549415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:30:01.004214Z","time spent":"545.193316ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T15:30:01.550202Z","caller":"traceutil/trace.go:171","msg":"trace[774909921] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"544.150399ms","start":"2026-09-18T15:30:01.004269Z","end":"2026-09-18T15:30:01.54842Z","steps":["trace[774909921] 'read index received' (duration: 151.661991ms)","trace[774909921] 'applied index is now lower than readState.Index' (duration: 392.485348ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:30:01.550474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.506612ms","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":"warn","ts":"2026-09-18T15:30:01.550454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.249495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-66dd49cfd8-5z4dr.18d673f585fd2013\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-09-18T15:30:01.550514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.455448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-09-18T15:30:01.550534Z","caller":"traceutil/trace.go:171","msg":"trace[1116708355] 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:4974; }","duration":"177.543673ms","start":"2026-09-18T15:30:01.372957Z","end":"2026-09-18T15:30:01.5505Z","steps":["trace[1116708355] 'agreement among raft nodes before linearized reading' (duration: 177.35732ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:01.550559Z","caller":"traceutil/trace.go:171","msg":"trace[1498814437] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4974; }","duration":"263.510168ms","start":"2026-09-18T15:30:01.287021Z","end":"2026-09-18T15:30:01.550531Z","steps":["trace[1498814437] 'agreement among raft nodes before linearized reading' (duration: 263.421637ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:01.550565Z","caller":"traceutil/trace.go:171","msg":"trace[781406250] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-66dd49cfd8-5z4dr.18d673f585fd2013; range_end:; response_count:1; response_revision:4974; }","duration":"263.387257ms","start":"2026-09-18T15:30:01.287167Z","end":"2026-09-18T15:30:01.550555Z","steps":["trace[781406250] 'agreement among raft nodes before linearized reading' (duration: 263.167403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:02.115404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.273112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:30:02.11547Z","caller":"traceutil/trace.go:171","msg":"trace[526092546] linearizableReadLoop","detail":"{readStateIndex:5176; appliedIndex:5175; }","duration":"206.203386ms","start":"2026-09-18T15:30:01.909256Z","end":"2026-09-18T15:30:02.115459Z","steps":["trace[526092546] 'read index received' (duration: 27.743871ms)","trace[526092546] 'applied index is now lower than readState.Index' (duration: 178.458925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:30:02.11562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.364588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T15:30:02.115519Z","caller":"traceutil/trace.go:171","msg":"trace[1503091084] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"294.254795ms","start":"2026-09-18T15:30:01.821241Z","end":"2026-09-18T15:30:02.115496Z","steps":["trace[1503091084] 'process raft request' (duration: 115.834772ms)","trace[1503091084] 'compare' (duration: 178.19801ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:30:02.115645Z","caller":"traceutil/trace.go:171","msg":"trace[1812870395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4985; }","duration":"206.419319ms","start":"2026-09-18T15:30:01.909219Z","end":"2026-09-18T15:30:02.115638Z","steps":["trace[1812870395] 'agreement among raft nodes before linearized reading' (duration: 206.297147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:02.115648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.745921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:30:02.115667Z","caller":"traceutil/trace.go:171","msg":"trace[1886920371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4985; }","duration":"107.799932ms","start":"2026-09-18T15:30:02.007859Z","end":"2026-09-18T15:30:02.115659Z","steps":["trace[1886920371] 'agreement among raft nodes before linearized reading' (duration: 107.773911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:02.11562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.67227ms","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-09-18T15:30:02.115785Z","caller":"traceutil/trace.go:171","msg":"trace[1525797462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4985; }","duration":"198.458232ms","start":"2026-09-18T15:30:01.91732Z","end":"2026-09-18T15:30:02.115779Z","steps":["trace[1525797462] 'agreement among raft nodes before linearized reading' (duration: 198.249648ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:04.348632Z","caller":"traceutil/trace.go:171","msg":"trace[407890761] linearizableReadLoop","detail":"{readStateIndex:5196; appliedIndex:5195; }","duration":"213.317808ms","start":"2026-09-18T15:30:04.135294Z","end":"2026-09-18T15:30:04.348612Z","steps":["trace[407890761] 'read index received' (duration: 213.074705ms)","trace[407890761] 'applied index is now lower than readState.Index' (duration: 242.103µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:30:04.348968Z","caller":"traceutil/trace.go:171","msg":"trace[1066313876] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"223.759123ms","start":"2026-09-18T15:30:04.125198Z","end":"2026-09-18T15:30:04.348957Z","steps":["trace[1066313876] 'process raft request' (duration: 223.260106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:04.349386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.079811ms","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-09-18T15:30:04.349417Z","caller":"traceutil/trace.go:171","msg":"trace[1260645007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5005; }","duration":"214.147342ms","start":"2026-09-18T15:30:04.135262Z","end":"2026-09-18T15:30:04.34941Z","steps":["trace[1260645007] 'agreement among raft nodes before linearized reading' (duration: 213.839398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:04.349609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.158936ms","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-09-18T15:30:04.34969Z","caller":"traceutil/trace.go:171","msg":"trace[1756524626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5005; }","duration":"170.257817ms","start":"2026-09-18T15:30:04.179397Z","end":"2026-09-18T15:30:04.349655Z","steps":["trace[1756524626] 'agreement among raft nodes before linearized reading' (duration: 170.008923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:04.349768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.338629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T15:30:04.349808Z","caller":"traceutil/trace.go:171","msg":"trace[833764391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5005; }","duration":"170.406599ms","start":"2026-09-18T15:30:04.179395Z","end":"2026-09-18T15:30:04.349801Z","steps":["trace[833764391] 'agreement among raft nodes before linearized reading' (duration: 170.323518ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:04.469404Z","caller":"traceutil/trace.go:171","msg":"trace[1866970561] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"113.07617ms","start":"2026-09-18T15:30:04.356305Z","end":"2026-09-18T15:30:04.469381Z","steps":["trace[1866970561] 'process raft request' (duration: 112.963848ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:04.469433Z","caller":"traceutil/trace.go:171","msg":"trace[1969539736] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"113.524068ms","start":"2026-09-18T15:30:04.355802Z","end":"2026-09-18T15:30:04.469326Z","steps":["trace[1969539736] 'process raft request' (duration: 113.364465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:06.201082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.302216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:30:06.201146Z","caller":"traceutil/trace.go:171","msg":"trace[1220297807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5072; }","duration":"195.409088ms","start":"2026-09-18T15:30:06.005722Z","end":"2026-09-18T15:30:06.201132Z","steps":["trace[1220297807] 'range keys from in-memory index tree' (duration: 195.225155ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:06.408224Z","caller":"traceutil/trace.go:171","msg":"trace[721650745] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"142.982629ms","start":"2026-09-18T15:30:06.265224Z","end":"2026-09-18T15:30:06.408207Z","steps":["trace[721650745] 'read index received' (duration: 142.825187ms)","trace[721650745] 'applied index is now lower than readState.Index' (duration: 156.842µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:30:06.408298Z","caller":"traceutil/trace.go:171","msg":"trace[906530252] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"205.323294ms","start":"2026-09-18T15:30:06.202967Z","end":"2026-09-18T15:30:06.40829Z","steps":["trace[906530252] 'process raft request' (duration: 205.126851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:06.408567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.333375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:30:06.408596Z","caller":"traceutil/trace.go:171","msg":"trace[1305516506] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5073; }","duration":"143.398886ms","start":"2026-09-18T15:30:06.265189Z","end":"2026-09-18T15:30:06.408588Z","steps":["trace[1305516506] 'agreement among raft nodes before linearized reading' (duration: 143.276674ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:06.640624Z","caller":"traceutil/trace.go:171","msg":"trace[693082472] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"228.506841ms","start":"2026-09-18T15:30:06.412096Z","end":"2026-09-18T15:30:06.640603Z","steps":["trace[693082472] 'process raft request' (duration: 228.230427ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:06.64585Z","caller":"traceutil/trace.go:171","msg":"trace[161962129] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5265; }","duration":"172.607888ms","start":"2026-09-18T15:30:06.473224Z","end":"2026-09-18T15:30:06.645831Z","steps":["trace[161962129] 'read index received' (duration: 166.962089ms)","trace[161962129] 'applied index is now lower than readState.Index' (duration: 5.644819ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:30:06.645937Z","caller":"traceutil/trace.go:171","msg":"trace[184421359] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"231.140553ms","start":"2026-09-18T15:30:06.414789Z","end":"2026-09-18T15:30:06.645929Z","steps":["trace[184421359] 'process raft request' (duration: 230.93919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:06.6461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.863091ms","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-09-18T15:30:06.646125Z","caller":"traceutil/trace.go:171","msg":"trace[1478976899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5075; }","duration":"172.927622ms","start":"2026-09-18T15:30:06.473191Z","end":"2026-09-18T15:30:06.646119Z","steps":["trace[1478976899] 'agreement among raft nodes before linearized reading' (duration: 172.829491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:06.646311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.983228ms","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-09-18T15:30:06.646339Z","caller":"traceutil/trace.go:171","msg":"trace[1382189239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5075; }","duration":"172.023958ms","start":"2026-09-18T15:30:06.47431Z","end":"2026-09-18T15:30:06.646334Z","steps":["trace[1382189239] 'agreement among raft nodes before linearized reading' (duration: 171.956097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:06.646457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.819836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T15:30:06.646476Z","caller":"traceutil/trace.go:171","msg":"trace[1476839543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5075; }","duration":"166.862657ms","start":"2026-09-18T15:30:06.479608Z","end":"2026-09-18T15:30:06.646471Z","steps":["trace[1476839543] 'agreement among raft nodes before linearized reading' (duration: 166.810267ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:30:08.718357Z","caller":"traceutil/trace.go:171","msg":"trace[587332750] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"325.127507ms","start":"2026-09-18T15:30:08.393175Z","end":"2026-09-18T15:30:08.718302Z","steps":["trace[587332750] 'process raft request' (duration: 324.839893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:30:08.718594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:30:08.393156Z","time spent":"325.317249ms","remote":"127.0.0.1:33958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":812,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:30:26.626602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2800} {"level":"info","ts":"2026-09-18T15:30:26.700452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2800,"took":"72.237177ms","hash":2722419128,"current-db-size-bytes":52699136,"current-db-size":"53 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T15:30:26.700504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2722419128,"revision":2800,"compact-revision":-1} {"level":"info","ts":"2026-09-18T15:34:26.353963Z","caller":"traceutil/trace.go:171","msg":"trace[2116358] transaction","detail":"{read_only:false; response_revision:7015; number_of_response:1; }","duration":"105.980355ms","start":"2026-09-18T15:34:26.247962Z","end":"2026-09-18T15:34:26.353942Z","steps":["trace[2116358] 'process raft request' (duration: 84.533474ms)","trace[2116358] 'compare' (duration: 21.222864ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:34:26.762679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.653154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:34:26.763344Z","caller":"traceutil/trace.go:171","msg":"trace[399311282] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"186.054662ms","start":"2026-09-18T15:34:26.577273Z","end":"2026-09-18T15:34:26.763328Z","steps":["trace[399311282] 'process raft request' (duration: 186.021111ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:34:26.763548Z","caller":"traceutil/trace.go:171","msg":"trace[137674849] linearizableReadLoop","detail":"{readStateIndex:7277; appliedIndex:7275; }","duration":"397.825426ms","start":"2026-09-18T15:34:26.365677Z","end":"2026-09-18T15:34:26.763502Z","steps":["trace[137674849] 'read index received' (duration: 79.802939ms)","trace[137674849] 'applied index is now lower than readState.Index' (duration: 318.019297ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:34:26.763575Z","caller":"traceutil/trace.go:171","msg":"trace[1009843800] transaction","detail":"{read_only:false; response_revision:7017; number_of_response:1; }","duration":"405.147435ms","start":"2026-09-18T15:34:26.358391Z","end":"2026-09-18T15:34:26.763538Z","steps":["trace[1009843800] 'process raft request' (duration: 287.53157ms)","trace[1009843800] 'compare' (duration: 116.2553ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:34:26.763451Z","caller":"traceutil/trace.go:171","msg":"trace[1479472035] transaction","detail":"{read_only:false; response_revision:7018; number_of_response:1; }","duration":"405.017141ms","start":"2026-09-18T15:34:26.358422Z","end":"2026-09-18T15:34:26.76344Z","steps":["trace[1479472035] 'process raft request' (duration: 404.331279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:26.763711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:26.358271Z","time spent":"405.368313ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:34:26.76379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:26.358412Z","time spent":"405.320331ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:34:26.763962Z","caller":"traceutil/trace.go:171","msg":"trace[538337669] transaction","detail":"{read_only:false; response_revision:7019; number_of_response:1; }","duration":"191.700967ms","start":"2026-09-18T15:34:26.572252Z","end":"2026-09-18T15:34:26.763953Z","steps":["trace[538337669] 'process raft request' (duration: 191.009844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:26.765694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.002877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T15:34:26.765743Z","caller":"traceutil/trace.go:171","msg":"trace[1022861967] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7020; }","duration":"400.08887ms","start":"2026-09-18T15:34:26.365641Z","end":"2026-09-18T15:34:26.765729Z","steps":["trace[1022861967] 'agreement among raft nodes before linearized reading' (duration: 397.96057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:26.765769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:26.365609Z","time spent":"400.153832ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-09-18T15:35:21.303231Z","caller":"traceutil/trace.go:171","msg":"trace[20114205] transaction","detail":"{read_only:false; response_revision:7318; number_of_response:1; }","duration":"240.239074ms","start":"2026-09-18T15:35:21.062974Z","end":"2026-09-18T15:35:21.303213Z","steps":["trace[20114205] 'process raft request' (duration: 240.11739ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:21.349393Z","caller":"traceutil/trace.go:171","msg":"trace[1783086452] transaction","detail":"{read_only:false; response_revision:7320; number_of_response:1; }","duration":"257.751471ms","start":"2026-09-18T15:35:21.091625Z","end":"2026-09-18T15:35:21.349377Z","steps":["trace[1783086452] 'process raft request' (duration: 257.658929ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:21.349408Z","caller":"traceutil/trace.go:171","msg":"trace[937201803] transaction","detail":"{read_only:false; response_revision:7319; number_of_response:1; }","duration":"257.822733ms","start":"2026-09-18T15:35:21.091546Z","end":"2026-09-18T15:35:21.349369Z","steps":["trace[937201803] 'process raft request' (duration: 256.792032ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:21.349557Z","caller":"traceutil/trace.go:171","msg":"trace[1770682176] linearizableReadLoop","detail":"{readStateIndex:7590; appliedIndex:7587; }","duration":"229.976112ms","start":"2026-09-18T15:35:21.119568Z","end":"2026-09-18T15:35:21.349544Z","steps":["trace[1770682176] 'read index received' (duration: 183.466411ms)","trace[1770682176] 'applied index is now lower than readState.Index' (duration: 46.508971ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:35:21.349534Z","caller":"traceutil/trace.go:171","msg":"trace[1807609664] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"216.247067ms","start":"2026-09-18T15:35:21.133253Z","end":"2026-09-18T15:35:21.3495Z","steps":["trace[1807609664] 'process raft request' (duration: 216.063091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:21.349779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.629833ms","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-09-18T15:35:21.349841Z","caller":"traceutil/trace.go:171","msg":"trace[1807938377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7321; }","duration":"186.734766ms","start":"2026-09-18T15:35:21.16309Z","end":"2026-09-18T15:35:21.349825Z","steps":["trace[1807938377] 'agreement among raft nodes before linearized reading' (duration: 186.558081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:21.35032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.739895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:35:21.350349Z","caller":"traceutil/trace.go:171","msg":"trace[867104846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7321; }","duration":"230.807917ms","start":"2026-09-18T15:35:21.119532Z","end":"2026-09-18T15:35:21.35034Z","steps":["trace[867104846] 'agreement among raft nodes before linearized reading' (duration: 230.064315ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:26.633885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5292} {"level":"info","ts":"2026-09-18T15:35:26.796268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5292,"took":"160.973805ms","hash":1870349502,"current-db-size-bytes":52699136,"current-db-size":"53 MB","current-db-size-in-use-bytes":18653184,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T15:35:26.796449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1870349502,"revision":5292,"compact-revision":2800} {"level":"info","ts":"2026-09-18T15:36:11.281077Z","caller":"traceutil/trace.go:171","msg":"trace[841776526] transaction","detail":"{read_only:false; response_revision:7569; number_of_response:1; }","duration":"110.845556ms","start":"2026-09-18T15:36:11.17017Z","end":"2026-09-18T15:36:11.281015Z","steps":["trace[841776526] 'process raft request' (duration: 81.021058ms)","trace[841776526] 'compare' (duration: 29.4886ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:36:53.840631Z","caller":"traceutil/trace.go:171","msg":"trace[1104615598] transaction","detail":"{read_only:false; response_revision:7787; number_of_response:1; }","duration":"107.781644ms","start":"2026-09-18T15:36:53.732823Z","end":"2026-09-18T15:36:53.840605Z","steps":["trace[1104615598] 'process raft request' (duration: 107.497617ms)"],"step_count":1}