{"level":"warn","ts":"2026-08-26T00:12:27.978178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:12:27.978267Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.196: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.196:2380","--initial-cluster=instance=https://162.253.55.196:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.196:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.196: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-08-26T00:12:27.978533Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:12:27.978547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.196:2380"]} {"level":"info","ts":"2026-08-26T00:12:27.978565Z","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-08-26T00:12:27.979322Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.196:2379"]} {"level":"info","ts":"2026-08-26T00:12:27.979477Z","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.196:2380"],"listen-peer-urls":["https://162.253.55.196:2380"],"advertise-client-urls":["https://162.253.55.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.196: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-08-26T00:12:27.984285Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.602944ms"} {"level":"info","ts":"2026-08-26T00:12:27.996873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40c23df05199fc95","cluster-id":"a4d434936ffe8e8"} {"level":"info","ts":"2026-08-26T00:12:27.996962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:12:27.996981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 became follower at term 0"} {"level":"info","ts":"2026-08-26T00:12:27.99699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40c23df05199fc95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:12:27.997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 became follower at term 1"} {"level":"info","ts":"2026-08-26T00:12:27.99704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 switched to configuration voters=(4666360266279746709)"} {"level":"warn","ts":"2026-08-26T00:12:28.005213Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:12:28.011813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:12:28.014434Z","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-08-26T00:12:28.016413Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40c23df05199fc95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:12:28.016553Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40c23df05199fc95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:12:28.01689Z","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-08-26T00:12:28.016984Z","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-08-26T00:12:28.017032Z","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-08-26T00:12:28.017682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 switched to configuration voters=(4666360266279746709)"} {"level":"info","ts":"2026-08-26T00:12:28.017994Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a4d434936ffe8e8","local-member-id":"40c23df05199fc95","added-peer-id":"40c23df05199fc95","added-peer-peer-urls":["https://162.253.55.196:2380"]} {"level":"info","ts":"2026-08-26T00:12:28.01958Z","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-08-26T00:12:28.019714Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.196:2380"} {"level":"info","ts":"2026-08-26T00:12:28.019782Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.196:2380"} {"level":"info","ts":"2026-08-26T00:12:28.020983Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40c23df05199fc95","initial-advertise-peer-urls":["https://162.253.55.196:2380"],"listen-peer-urls":["https://162.253.55.196:2380"],"advertise-client-urls":["https://162.253.55.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:12:28.021035Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:12:28.597583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:12:28.597661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:12:28.597685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 received MsgPreVoteResp from 40c23df05199fc95 at term 1"} {"level":"info","ts":"2026-08-26T00:12:28.597775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:12:28.597814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 received MsgVoteResp from 40c23df05199fc95 at term 2"} {"level":"info","ts":"2026-08-26T00:12:28.597854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40c23df05199fc95 became leader at term 2"} {"level":"info","ts":"2026-08-26T00:12:28.597874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40c23df05199fc95 elected leader 40c23df05199fc95 at term 2"} {"level":"info","ts":"2026-08-26T00:12:28.599171Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40c23df05199fc95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.196:2379]}","request-path":"/0/members/40c23df05199fc95/attributes","cluster-id":"a4d434936ffe8e8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:12:28.599218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:12:28.599216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:12:28.599321Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:28.599725Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:12:28.599751Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:12:28.600307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a4d434936ffe8e8","local-member-id":"40c23df05199fc95","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:28.600443Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:28.600492Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:28.601924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:12:28.6026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.196:2379"} {"level":"info","ts":"2026-08-26T00:12:32.382336Z","caller":"traceutil/trace.go:171","msg":"trace[225957129] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"174.259908ms","start":"2026-08-26T00:12:32.208022Z","end":"2026-08-26T00:12:32.382282Z","steps":["trace[225957129] 'process raft request' (duration: 173.947551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:32.884466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.78305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:12:32.884744Z","caller":"traceutil/trace.go:171","msg":"trace[640597287] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"498.536932ms","start":"2026-08-26T00:12:32.386142Z","end":"2026-08-26T00:12:32.884679Z","steps":["trace[640597287] 'process raft request' (duration: 218.986525ms)","trace[640597287] 'compare' (duration: 278.577936ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:12:32.884891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.386122Z","time spent":"498.730316ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:12:32.903957Z","caller":"traceutil/trace.go:171","msg":"trace[1154170652] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"513.917014ms","start":"2026-08-26T00:12:32.390028Z","end":"2026-08-26T00:12:32.903945Z","steps":["trace[1154170652] 'process raft request' (duration: 513.850983ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:12:32.903982Z","caller":"traceutil/trace.go:171","msg":"trace[1461295873] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:211; }","duration":"274.910413ms","start":"2026-08-26T00:12:32.629045Z","end":"2026-08-26T00:12:32.903955Z","steps":["trace[1461295873] 'read index received' (duration: 274.903743ms)","trace[1461295873] 'applied index is now lower than readState.Index' (duration: 5.52µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:12:32.904035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.390015Z","time spent":"513.985327ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:12:32.904381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.287132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:12:32.904484Z","caller":"traceutil/trace.go:171","msg":"trace[1644185518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:207; }","duration":"275.462666ms","start":"2026-08-26T00:12:32.628994Z","end":"2026-08-26T00:12:32.904457Z","steps":["trace[1644185518] 'agreement among raft nodes before linearized reading' (duration: 275.057237ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:12:33.243966Z","caller":"traceutil/trace.go:171","msg":"trace[593098827] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"335.280735ms","start":"2026-08-26T00:12:32.908661Z","end":"2026-08-26T00:12:33.243942Z","steps":["trace[593098827] 'process raft request' (duration: 335.172892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:33.244166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.908637Z","time spent":"335.46302ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:12:33.244349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.890821Z","time spent":"353.526883ms","remote":"127.0.0.1:58760","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-26T00:12:33.244347Z","caller":"traceutil/trace.go:171","msg":"trace[1152639890] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"340.267737ms","start":"2026-08-26T00:12:32.904023Z","end":"2026-08-26T00:12:33.24429Z","steps":["trace[1152639890] 'read index received' (duration: 256.513053ms)","trace[1152639890] 'applied index is now lower than readState.Index' (duration: 83.748324ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:12:33.244754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.908569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-ar6clb\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-08-26T00:12:33.244833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.070277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:12:33.244897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.063707ms","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-08-26T00:12:33.245103Z","caller":"traceutil/trace.go:171","msg":"trace[1666284515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:208; }","duration":"355.304302ms","start":"2026-08-26T00:12:32.889771Z","end":"2026-08-26T00:12:33.245075Z","steps":["trace[1666284515] 'agreement among raft nodes before linearized reading' (duration: 354.678708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:33.245226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.889748Z","time spent":"355.458115ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:12:33.244967Z","caller":"traceutil/trace.go:171","msg":"trace[1629378378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:208; }","duration":"355.267261ms","start":"2026-08-26T00:12:32.889664Z","end":"2026-08-26T00:12:33.244931Z","steps":["trace[1629378378] 'agreement among raft nodes before linearized reading' (duration: 354.903463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:33.245577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.889643Z","time spent":"355.914196ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:12:33.244864Z","caller":"traceutil/trace.go:171","msg":"trace[1023805864] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-ar6clb; range_end:; response_count:1; response_revision:208; }","duration":"336.022441ms","start":"2026-08-26T00:12:32.908783Z","end":"2026-08-26T00:12:33.244805Z","steps":["trace[1023805864] 'agreement among raft nodes before linearized reading' (duration: 335.875808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:33.245772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:12:32.908766Z","time spent":"336.981953ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":562,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-ar6clb\" "} {"level":"warn","ts":"2026-08-26T00:13:05.987421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.855489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:13:05.987661Z","caller":"traceutil/trace.go:171","msg":"trace[1949143486] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"318.585621ms","start":"2026-08-26T00:13:05.669037Z","end":"2026-08-26T00:13:05.987622Z","steps":["trace[1949143486] 'process raft request' (duration: 163.277624ms)","trace[1949143486] 'compare' (duration: 154.692964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:13:05.987816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:05.669012Z","time spent":"318.715904ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:05.988244Z","caller":"traceutil/trace.go:171","msg":"trace[1757625333] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"315.098558ms","start":"2026-08-26T00:13:05.673123Z","end":"2026-08-26T00:13:05.988221Z","steps":["trace[1757625333] 'process raft request' (duration: 314.435575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:05.988331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:05.670453Z","time spent":"317.829215ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:05.988711Z","caller":"traceutil/trace.go:171","msg":"trace[857322428] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"306.99992ms","start":"2026-08-26T00:13:05.681685Z","end":"2026-08-26T00:13:05.988685Z","steps":["trace[857322428] 'process raft request' (duration: 305.961399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:05.988804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:05.681667Z","time spent":"307.086552ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:05.988818Z","caller":"traceutil/trace.go:171","msg":"trace[1140677829] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"307.048651ms","start":"2026-08-26T00:13:05.68174Z","end":"2026-08-26T00:13:05.988789Z","steps":["trace[1140677829] 'process raft request' (duration: 306.812996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:05.988905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:05.68172Z","time spent":"307.144533ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:05.989154Z","caller":"traceutil/trace.go:171","msg":"trace[125705477] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"303.61431ms","start":"2026-08-26T00:13:05.685452Z","end":"2026-08-26T00:13:05.989067Z","steps":["trace[125705477] 'process raft request' (duration: 303.276683ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:05.989213Z","caller":"traceutil/trace.go:171","msg":"trace[887900369] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"165.124212ms","start":"2026-08-26T00:13:05.82407Z","end":"2026-08-26T00:13:05.989194Z","steps":["trace[887900369] 'process raft request' (duration: 164.995388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:05.989255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:05.685443Z","time spent":"303.761464ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:09.335184Z","caller":"traceutil/trace.go:171","msg":"trace[1314240050] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"310.379306ms","start":"2026-08-26T00:13:09.024764Z","end":"2026-08-26T00:13:09.335144Z","steps":["trace[1314240050] 'process raft request' (duration: 309.864226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:09.335508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:09.024727Z","time spent":"310.55158ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:13:13.231647Z","caller":"traceutil/trace.go:171","msg":"trace[1260959550] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"264.897657ms","start":"2026-08-26T00:13:12.96672Z","end":"2026-08-26T00:13:13.231618Z","steps":["trace[1260959550] 'process raft request' (duration: 264.296635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:13.850364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116905540,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:13:14.092472Z","caller":"traceutil/trace.go:171","msg":"trace[1088564604] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"743.086988ms","start":"2026-08-26T00:13:13.34936Z","end":"2026-08-26T00:13:14.092447Z","steps":["trace[1088564604] 'read index received' (duration: 742.979336ms)","trace[1088564604] 'applied index is now lower than readState.Index' (duration: 106.402µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:14.092527Z","caller":"traceutil/trace.go:171","msg":"trace[1126060997] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"800.91585ms","start":"2026-08-26T00:13:13.291559Z","end":"2026-08-26T00:13:14.092475Z","steps":["trace[1126060997] 'process raft request' (duration: 800.750437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:14.092712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:13.291536Z","time spent":"801.086433ms","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:13:14.092831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.450865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-78h59\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-26T00:13:14.092917Z","caller":"traceutil/trace.go:171","msg":"trace[34030555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-78h59; range_end:; response_count:1; response_revision:599; }","duration":"743.567408ms","start":"2026-08-26T00:13:13.349315Z","end":"2026-08-26T00:13:14.092883Z","steps":["trace[34030555] 'agreement among raft nodes before linearized reading' (duration: 743.274872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:14.092981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:13.3493Z","time spent":"743.666409ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-78h59\" "} {"level":"warn","ts":"2026-08-26T00:13:14.093216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.928397ms","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-08-26T00:13:14.093317Z","caller":"traceutil/trace.go:171","msg":"trace[627774794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"566.069999ms","start":"2026-08-26T00:13:13.52719Z","end":"2026-08-26T00:13:14.09326Z","steps":["trace[627774794] 'agreement among raft nodes before linearized reading' (duration: 565.868725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:14.093395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:13.52717Z","time spent":"566.182561ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:13:14.093465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.146587ms","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-08-26T00:13:14.093606Z","caller":"traceutil/trace.go:171","msg":"trace[513595452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"581.442554ms","start":"2026-08-26T00:13:13.512134Z","end":"2026-08-26T00:13:14.093577Z","steps":["trace[513595452] 'agreement among raft nodes before linearized reading' (duration: 580.870101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:14.093705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:13.512111Z","time spent":"581.579757ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:13:15.659463Z","caller":"traceutil/trace.go:171","msg":"trace[1285948132] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"125.337894ms","start":"2026-08-26T00:13:15.534097Z","end":"2026-08-26T00:13:15.659435Z","steps":["trace[1285948132] 'process raft request' (duration: 125.17474ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:13.766489Z","caller":"traceutil/trace.go:171","msg":"trace[1950582716] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"115.73597ms","start":"2026-08-26T00:14:13.650723Z","end":"2026-08-26T00:14:13.766459Z","steps":["trace[1950582716] 'read index received' (duration: 115.282612ms)","trace[1950582716] 'applied index is now lower than readState.Index' (duration: 452.498µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:14:13.766596Z","caller":"traceutil/trace.go:171","msg":"trace[817081694] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"154.483994ms","start":"2026-08-26T00:14:13.612048Z","end":"2026-08-26T00:14:13.766532Z","steps":["trace[817081694] 'process raft request' (duration: 154.225139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:14:13.766956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.19954ms","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-08-26T00:14:13.767079Z","caller":"traceutil/trace.go:171","msg":"trace[170389458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"116.402753ms","start":"2026-08-26T00:14:13.650652Z","end":"2026-08-26T00:14:13.767055Z","steps":["trace[170389458] 'agreement among raft nodes before linearized reading' (duration: 116.019436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:15:32.895623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.588408ms","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-08-26T00:15:32.895994Z","caller":"traceutil/trace.go:171","msg":"trace[2092969328] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1966; }","duration":"335.989175ms","start":"2026-08-26T00:15:32.559984Z","end":"2026-08-26T00:15:32.895973Z","steps":["trace[2092969328] 'range keys from in-memory index tree' (duration: 335.420524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:15:32.896038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:15:32.55997Z","time spent":"336.056676ms","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-26T00:15:32.904479Z","caller":"traceutil/trace.go:171","msg":"trace[83749665] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"154.793263ms","start":"2026-08-26T00:15:32.749668Z","end":"2026-08-26T00:15:32.904461Z","steps":["trace[83749665] 'process raft request' (duration: 154.525108ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:33.453547Z","caller":"traceutil/trace.go:171","msg":"trace[978967189] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"188.284604ms","start":"2026-08-26T00:15:33.26524Z","end":"2026-08-26T00:15:33.453525Z","steps":["trace[978967189] 'read index received' (duration: 188.123311ms)","trace[978967189] 'applied index is now lower than readState.Index' (duration: 160.423µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:33.453606Z","caller":"traceutil/trace.go:171","msg":"trace[471764846] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"431.812117ms","start":"2026-08-26T00:15:33.021766Z","end":"2026-08-26T00:15:33.453579Z","steps":["trace[471764846] 'process raft request' (duration: 431.639984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:15:33.453682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.674333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:15:33.453713Z","caller":"traceutil/trace.go:171","msg":"trace[1296775601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1971; }","duration":"166.754095ms","start":"2026-08-26T00:15:33.286951Z","end":"2026-08-26T00:15:33.453705Z","steps":["trace[1296775601] 'agreement among raft nodes before linearized reading' (duration: 166.689793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:15:33.453751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:15:33.021744Z","time spent":"431.926209ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:15:33.45394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.667391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-26T00:15:33.45404Z","caller":"traceutil/trace.go:171","msg":"trace[930664565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1971; }","duration":"188.831124ms","start":"2026-08-26T00:15:33.265188Z","end":"2026-08-26T00:15:33.454019Z","steps":["trace[930664565] 'agreement among raft nodes before linearized reading' (duration: 188.413977ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:40.550532Z","caller":"traceutil/trace.go:171","msg":"trace[325346662] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"141.617792ms","start":"2026-08-26T00:15:40.408895Z","end":"2026-08-26T00:15:40.550513Z","steps":["trace[325346662] 'process raft request' (duration: 122.364244ms)","trace[325346662] 'compare' (duration: 19.189346ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:46.451953Z","caller":"traceutil/trace.go:171","msg":"trace[276741530] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"143.508165ms","start":"2026-08-26T00:15:46.308411Z","end":"2026-08-26T00:15:46.451919Z","steps":["trace[276741530] 'process raft request' (duration: 143.360382ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:46.454414Z","caller":"traceutil/trace.go:171","msg":"trace[1958152441] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"128.459576ms","start":"2026-08-26T00:15:46.325942Z","end":"2026-08-26T00:15:46.454401Z","steps":["trace[1958152441] 'process raft request' (duration: 128.431966ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:46.454631Z","caller":"traceutil/trace.go:171","msg":"trace[140569506] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"145.244017ms","start":"2026-08-26T00:15:46.30938Z","end":"2026-08-26T00:15:46.454624Z","steps":["trace[140569506] 'process raft request' (duration: 144.911811ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:01.779999Z","caller":"traceutil/trace.go:171","msg":"trace[694930743] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"136.769684ms","start":"2026-08-26T00:16:01.643199Z","end":"2026-08-26T00:16:01.779968Z","steps":["trace[694930743] 'process raft request' (duration: 123.299236ms)","trace[694930743] 'compare' (duration: 13.312825ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:01.870733Z","caller":"traceutil/trace.go:171","msg":"trace[2088048285] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2272; }","duration":"164.872716ms","start":"2026-08-26T00:16:01.705839Z","end":"2026-08-26T00:16:01.870711Z","steps":["trace[2088048285] 'read index received' (duration: 60.670245ms)","trace[2088048285] 'applied index is now lower than readState.Index' (duration: 104.201451ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:01.870763Z","caller":"traceutil/trace.go:171","msg":"trace[61946640] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"196.193376ms","start":"2026-08-26T00:16:01.674544Z","end":"2026-08-26T00:16:01.870738Z","steps":["trace[61946640] 'process raft request' (duration: 196.038143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:01.870975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.11428ms","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-08-26T00:16:01.87103Z","caller":"traceutil/trace.go:171","msg":"trace[490715979] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2186; }","duration":"165.203741ms","start":"2026-08-26T00:16:01.705813Z","end":"2026-08-26T00:16:01.871016Z","steps":["trace[490715979] 'agreement among raft nodes before linearized reading' (duration: 165.001888ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:01.969159Z","caller":"traceutil/trace.go:171","msg":"trace[448345939] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"114.039663ms","start":"2026-08-26T00:16:01.855088Z","end":"2026-08-26T00:16:01.969127Z","steps":["trace[448345939] 'process raft request' (duration: 113.781208ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:07.343564Z","caller":"traceutil/trace.go:171","msg":"trace[1515950545] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"134.549562ms","start":"2026-08-26T00:16:07.208986Z","end":"2026-08-26T00:16:07.343536Z","steps":["trace[1515950545] 'process raft request' (duration: 95.646372ms)","trace[1515950545] 'compare' (duration: 38.34319ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:08.142511Z","caller":"traceutil/trace.go:171","msg":"trace[2099753362] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"156.08018ms","start":"2026-08-26T00:16:07.9864Z","end":"2026-08-26T00:16:08.14248Z","steps":["trace[2099753362] 'process raft request' (duration: 155.864686ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.646992Z","caller":"traceutil/trace.go:171","msg":"trace[1358277282] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"114.185324ms","start":"2026-08-26T00:16:08.532783Z","end":"2026-08-26T00:16:08.646968Z","steps":["trace[1358277282] 'process raft request' (duration: 113.999791ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.652295Z","caller":"traceutil/trace.go:171","msg":"trace[1218016027] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2308; }","duration":"100.448129ms","start":"2026-08-26T00:16:08.551823Z","end":"2026-08-26T00:16:08.652271Z","steps":["trace[1218016027] 'read index received' (duration: 95.024859ms)","trace[1218016027] 'applied index is now lower than readState.Index' (duration: 5.42235ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:08.652371Z","caller":"traceutil/trace.go:171","msg":"trace[854781895] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"109.564148ms","start":"2026-08-26T00:16:08.54276Z","end":"2026-08-26T00:16:08.652324Z","steps":["trace[854781895] 'process raft request' (duration: 109.303844ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.652499Z","caller":"traceutil/trace.go:171","msg":"trace[1116834161] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"107.266047ms","start":"2026-08-26T00:16:08.545089Z","end":"2026-08-26T00:16:08.652355Z","steps":["trace[1116834161] 'process raft request' (duration: 107.110414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:08.652599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.751626ms","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-08-26T00:16:08.652787Z","caller":"traceutil/trace.go:171","msg":"trace[373678919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2222; }","duration":"100.994429ms","start":"2026-08-26T00:16:08.551778Z","end":"2026-08-26T00:16:08.652772Z","steps":["trace[373678919] 'agreement among raft nodes before linearized reading' (duration: 100.587942ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:09.585335Z","caller":"traceutil/trace.go:171","msg":"trace[1798368905] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"347.082866ms","start":"2026-08-26T00:16:09.238221Z","end":"2026-08-26T00:16:09.585303Z","steps":["trace[1798368905] 'read index received' (duration: 346.813061ms)","trace[1798368905] 'applied index is now lower than readState.Index' (duration: 268.735µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:09.58544Z","caller":"traceutil/trace.go:171","msg":"trace[8554683] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"371.942436ms","start":"2026-08-26T00:16:09.213461Z","end":"2026-08-26T00:16:09.585404Z","steps":["trace[8554683] 'process raft request' (duration: 371.645591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:09.5857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.465643ms","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-08-26T00:16:09.585728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:09.213427Z","time spent":"372.1337ms","remote":"127.0.0.1:58872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8560,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:09.585771Z","caller":"traceutil/trace.go:171","msg":"trace[1301119105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2225; }","duration":"347.591365ms","start":"2026-08-26T00:16:09.238167Z","end":"2026-08-26T00:16:09.585759Z","steps":["trace[1301119105] 'agreement among raft nodes before linearized reading' (duration: 347.365861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:09.586396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:09.238149Z","time spent":"347.995752ms","remote":"127.0.0.1:58982","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-08-26T00:16:09.58587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.400537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:16:09.587472Z","caller":"traceutil/trace.go:171","msg":"trace[322413298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2225; }","duration":"237.741602ms","start":"2026-08-26T00:16:09.349421Z","end":"2026-08-26T00:16:09.587162Z","steps":["trace[322413298] 'agreement among raft nodes before linearized reading' (duration: 236.397998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:09.586627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.845138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:09.587616Z","caller":"traceutil/trace.go:171","msg":"trace[716308057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2225; }","duration":"302.915018ms","start":"2026-08-26T00:16:09.284687Z","end":"2026-08-26T00:16:09.587602Z","steps":["trace[716308057] 'agreement among raft nodes before linearized reading' (duration: 301.015293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:09.587699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:09.284669Z","time spent":"302.97145ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:16:09.769882Z","caller":"traceutil/trace.go:171","msg":"trace[841790156] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"177.038909ms","start":"2026-08-26T00:16:09.592805Z","end":"2026-08-26T00:16:09.769844Z","steps":["trace[841790156] 'process raft request' (duration: 128.003071ms)","trace[841790156] 'compare' (duration: 48.831483ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:09.947445Z","caller":"traceutil/trace.go:171","msg":"trace[77692668] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2316; }","duration":"103.858573ms","start":"2026-08-26T00:16:09.843561Z","end":"2026-08-26T00:16:09.94742Z","steps":["trace[77692668] 'read index received' (duration: 95.139932ms)","trace[77692668] 'applied index is now lower than readState.Index' (duration: 8.717751ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:09.947635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.075057ms","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-08-26T00:16:09.947675Z","caller":"traceutil/trace.go:171","msg":"trace[249829865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2228; }","duration":"104.134588ms","start":"2026-08-26T00:16:09.843529Z","end":"2026-08-26T00:16:09.947664Z","steps":["trace[249829865] 'agreement among raft nodes before linearized reading' (duration: 103.989805ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:09.947803Z","caller":"traceutil/trace.go:171","msg":"trace[2088589690] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"173.97154ms","start":"2026-08-26T00:16:09.773571Z","end":"2026-08-26T00:16:09.947543Z","steps":["trace[2088589690] 'process raft request' (duration: 165.121957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:46.957088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:47.458198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:47.959215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:48.459639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:48.96031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:49.287683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0005537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:49.287963Z","caller":"traceutil/trace.go:171","msg":"trace[1036519810] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000839375s","start":"2026-08-26T00:16:47.287054Z","end":"2026-08-26T00:16:49.287893Z","steps":["trace[1036519810] 'agreement among raft nodes before linearized reading' (duration: 2.00058233s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.288126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.287035Z","time spent":"2.00107662s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:49.461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909407,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:49.616954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.491977537s","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-08-26T00:16:49.61703Z","caller":"traceutil/trace.go:171","msg":"trace[334012846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2430; }","duration":"3.492062648s","start":"2026-08-26T00:16:46.12495Z","end":"2026-08-26T00:16:49.617013Z","steps":["trace[334012846] 'range keys from in-memory index tree' (duration: 3.491833704s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.617072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.124897Z","time spent":"3.49216455s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:16:49.618418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.491922095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-26T00:16:49.618467Z","caller":"traceutil/trace.go:171","msg":"trace[1751503333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2430; }","duration":"3.492002926s","start":"2026-08-26T00:16:46.126451Z","end":"2026-08-26T00:16:49.618454Z","steps":["trace[1751503333] 'range keys from in-memory index tree' (duration: 3.491707971s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.618496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.126437Z","time spent":"3.492051167s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:16:49.646894Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.351990312s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:16:49.647328Z","caller":"traceutil/trace.go:171","msg":"trace[1139957876] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"3.352533703s","start":"2026-08-26T00:16:46.294771Z","end":"2026-08-26T00:16:49.647305Z","steps":["trace[1139957876] 'process raft request' (duration: 3.35237941s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.647483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.294747Z","time spent":"3.352658115s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:51.288598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000508785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:51.288758Z","caller":"traceutil/trace.go:171","msg":"trace[479932902] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00072243s","start":"2026-08-26T00:16:49.288015Z","end":"2026-08-26T00:16:51.288737Z","steps":["trace[479932902] 'agreement among raft nodes before linearized reading' (duration: 2.000543466s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:51.288836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.28799Z","time spent":"2.000832522s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:51.782868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.785155Z","time spent":"4.997707708s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:51.795826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.797914Z","time spent":"4.997907942s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:51.846693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999301937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:51.846767Z","caller":"traceutil/trace.go:171","msg":"trace[1520871952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99943173s","start":"2026-08-26T00:16:46.847321Z","end":"2026-08-26T00:16:51.846752Z","steps":["trace[1520871952] 'agreement among raft nodes before linearized reading' (duration: 4.999336808s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:51.846808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.847301Z","time spent":"4.99949825s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:52.139919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999854121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:52.140022Z","caller":"traceutil/trace.go:171","msg":"trace[1045026190] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999992404s","start":"2026-08-26T00:16:50.140011Z","end":"2026-08-26T00:16:52.140003Z","steps":["trace[1045026190] 'agreement among raft nodes before linearized reading' (duration: 1.999871331s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:52.140067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.140001Z","time spent":"2.000057906s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:52.331971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.33305Z","time spent":"4.998913128s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:52.935021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999689759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:52.935113Z","caller":"traceutil/trace.go:171","msg":"trace[1997317558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999846232s","start":"2026-08-26T00:16:47.935247Z","end":"2026-08-26T00:16:52.935094Z","steps":["trace[1997317558] 'agreement among raft nodes before linearized reading' (duration: 4.99972806s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:52.935161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.935224Z","time spent":"4.999927743s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:16:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:53.296917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000709914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:53.297008Z","caller":"traceutil/trace.go:171","msg":"trace[609918726] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000854517s","start":"2026-08-26T00:16:51.296133Z","end":"2026-08-26T00:16:53.296987Z","steps":["trace[609918726] 'agreement among raft nodes before linearized reading' (duration: 2.000734985s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:53.297059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.296118Z","time spent":"2.000931598s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:53.823734Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7c95a03b693df088","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:16:54.621011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.622617Z","time spent":"4.998376665s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:55.110381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.110172Z","time spent":"7.000202125s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:55.297382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999865084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:55.297448Z","caller":"traceutil/trace.go:171","msg":"trace[1581464868] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999993498s","start":"2026-08-26T00:16:53.297439Z","end":"2026-08-26T00:16:55.297433Z","steps":["trace[1581464868] 'agreement among raft nodes before linearized reading' (duration: 1.999910495s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:55.297484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.297414Z","time spent":"2.000064249s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.126938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.62257Z","time spent":"6.504363718s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.651799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.653375Z","time spent":"4.998415655s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.770868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.769838Z","time spent":"7.001023147s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:56.783587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998822992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.783749Z","caller":"traceutil/trace.go:171","msg":"trace[268487036] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"4.998986085s","start":"2026-08-26T00:16:51.784671Z","end":"2026-08-26T00:16:56.783657Z","steps":["trace[268487036] 'agreement among raft nodes before linearized reading' (duration: 4.998871473s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.783869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.784655Z","time spent":"4.999198198s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.79716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999433774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.797237Z","caller":"traceutil/trace.go:171","msg":"trace[585263758] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.999546646s","start":"2026-08-26T00:16:51.797672Z","end":"2026-08-26T00:16:56.797219Z","steps":["trace[585263758] 'agreement among raft nodes before linearized reading' (duration: 4.999451984s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.797284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.797662Z","time spent":"4.999610477s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.846524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997886024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.846597Z","caller":"traceutil/trace.go:171","msg":"trace[419033139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998022798s","start":"2026-08-26T00:16:53.848555Z","end":"2026-08-26T00:16:56.846578Z","steps":["trace[419033139] 'agreement among raft nodes before linearized reading' (duration: 2.997918415s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.846638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.848534Z","time spent":"2.998095089s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.194658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.194456Z","time spent":"7.000195169s","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:57.30571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000575364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:57.305859Z","caller":"traceutil/trace.go:171","msg":"trace[1405536143] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000785477s","start":"2026-08-26T00:16:55.305054Z","end":"2026-08-26T00:16:57.30584Z","steps":["trace[1405536143] 'agreement among raft nodes before linearized reading' (duration: 2.000608254s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.30594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:55.305034Z","time spent":"2.00089271s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.330984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.997050326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.331094Z","caller":"traceutil/trace.go:171","msg":"trace[1700986440] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"4.997151458s","start":"2026-08-26T00:16:52.333875Z","end":"2026-08-26T00:16:57.331027Z","steps":["trace[1700986440] 'agreement among raft nodes before linearized reading' (duration: 4.997084907s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.331132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.333856Z","time spent":"4.99726746s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.81624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999674008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.816323Z","caller":"traceutil/trace.go:171","msg":"trace[16017565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.99980941s","start":"2026-08-26T00:16:47.816495Z","end":"2026-08-26T00:16:57.816305Z","steps":["trace[16017565] 'agreement among raft nodes before linearized reading' (duration: 9.999705098s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.816376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.816475Z","time spent":"9.999885992s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.934951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998664075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.935026Z","caller":"traceutil/trace.go:171","msg":"trace[1902405198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998789207s","start":"2026-08-26T00:16:54.936217Z","end":"2026-08-26T00:16:57.935006Z","steps":["trace[1902405198] 'agreement among raft nodes before linearized reading' (duration: 2.998699176s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.935075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.936196Z","time spent":"2.998869598s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:59.244897Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.597728286s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-26T00:16:59.259223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.937388Z","time spent":"1.321827591s","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:59.262007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.04491298s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.262176Z","caller":"traceutil/trace.go:171","msg":"trace[855468095] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2431; }","duration":"7.045094963s","start":"2026-08-26T00:16:52.217057Z","end":"2026-08-26T00:16:59.262152Z","steps":["trace[855468095] 'range keys from in-memory index tree' (duration: 7.04489284s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.297476Z","caller":"traceutil/trace.go:171","msg":"trace[1966061997] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2526; }","duration":"12.840853286s","start":"2026-08-26T00:16:46.456589Z","end":"2026-08-26T00:16:59.297442Z","steps":["trace[1966061997] 'read index received' (duration: 3.190598393s)","trace[1966061997] 'applied index is now lower than readState.Index' (duration: 9.650248963s)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:59.297476Z","caller":"traceutil/trace.go:171","msg":"trace[25156685] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"6.929760444s","start":"2026-08-26T00:16:52.367689Z","end":"2026-08-26T00:16:59.29745Z","steps":["trace[25156685] 'process raft request' (duration: 6.929685363s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.297696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.367662Z","time spent":"6.929929187s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.297776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.841174553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-08-26T00:16:59.297822Z","caller":"traceutil/trace.go:171","msg":"trace[641338310] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2442; }","duration":"12.841274345s","start":"2026-08-26T00:16:46.456533Z","end":"2026-08-26T00:16:59.297808Z","steps":["trace[641338310] 'agreement among raft nodes before linearized reading' (duration: 12.841088771s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.297849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.456514Z","time spent":"12.841328437s","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5450,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-26T00:16:59.29783Z","caller":"traceutil/trace.go:171","msg":"trace[1666049843] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"6.026015692s","start":"2026-08-26T00:16:53.271783Z","end":"2026-08-26T00:16:59.297799Z","steps":["trace[1666049843] 'process raft request' (duration: 6.025620834s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.298006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.271693Z","time spent":"6.026210375s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.298076Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"71.621µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-08-26T00:16:59.298202Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7c95a03b693df088","error":"lease not found"} {"level":"info","ts":"2026-08-26T00:16:59.298288Z","caller":"traceutil/trace.go:171","msg":"trace[15854868] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"3.690423762s","start":"2026-08-26T00:16:55.607849Z","end":"2026-08-26T00:16:59.298273Z","steps":["trace[15854868] 'process raft request' (duration: 3.69027873s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.298417Z","caller":"traceutil/trace.go:171","msg":"trace[310031999] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2443; }","duration":"3.172207603s","start":"2026-08-26T00:16:56.126194Z","end":"2026-08-26T00:16:59.298401Z","steps":["trace[310031999] 'process raft request' (duration: 3.172002669s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.298467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:55.607822Z","time spent":"3.690589906s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.298498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.126172Z","time spent":"3.172287175s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":511,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.298585Z","caller":"traceutil/trace.go:171","msg":"trace[1259783622] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2443; }","duration":"3.170003962s","start":"2026-08-26T00:16:56.128572Z","end":"2026-08-26T00:16:59.298576Z","steps":["trace[1259783622] 'process raft request' (duration: 3.169758617s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.298624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.128562Z","time spent":"3.170039753s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":46,"response count":0,"response size":466,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.298815Z","caller":"traceutil/trace.go:171","msg":"trace[488918502] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2443; }","duration":"2.523393355s","start":"2026-08-26T00:16:56.7754Z","end":"2026-08-26T00:16:59.298794Z","steps":["trace[488918502] 'process raft request' (duration: 2.522967147s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.298888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.775377Z","time spent":"2.523473446s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.305954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00002024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:59.306007Z","caller":"traceutil/trace.go:171","msg":"trace[1636199182] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000110112s","start":"2026-08-26T00:16:57.305886Z","end":"2026-08-26T00:16:59.305996Z","steps":["trace[1636199182] 'agreement among raft nodes before linearized reading' (duration: 2.000045731s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.306042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.305869Z","time spent":"2.000166343s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-08-26T00:16:59.315969Z","caller":"traceutil/trace.go:171","msg":"trace[1157548120] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"2.467006264s","start":"2026-08-26T00:16:56.848942Z","end":"2026-08-26T00:16:59.315949Z","steps":["trace[1157548120] 'process raft request' (duration: 2.466835901s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.84892Z","time spent":"2.467113487s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.316308Z","caller":"traceutil/trace.go:171","msg":"trace[389808136] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"2.116428526s","start":"2026-08-26T00:16:57.199865Z","end":"2026-08-26T00:16:59.316294Z","steps":["trace[389808136] 'process raft request' (duration: 2.116044129s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.199838Z","time spent":"2.116512378s","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:16:59.316416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.136639851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.316446Z","caller":"traceutil/trace.go:171","msg":"trace[946023654] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2448; }","duration":"7.136672252s","start":"2026-08-26T00:16:52.179765Z","end":"2026-08-26T00:16:59.316438Z","steps":["trace[946023654] 'agreement among raft nodes before linearized reading' (duration: 7.136604621s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.179708Z","time spent":"7.136755663s","remote":"127.0.0.1:50764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.316542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.482612266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.316568Z","caller":"traceutil/trace.go:171","msg":"trace[375752720] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2448; }","duration":"7.482662616s","start":"2026-08-26T00:16:51.833897Z","end":"2026-08-26T00:16:59.31656Z","steps":["trace[375752720] 'agreement among raft nodes before linearized reading' (duration: 7.482616215s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702837328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.31661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.833887Z","time spent":"7.482716318s","remote":"127.0.0.1:40296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.316631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.663897666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-08-26T00:16:59.316636Z","caller":"traceutil/trace.go:171","msg":"trace[1915965911] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2448; }","duration":"1.70292547s","start":"2026-08-26T00:16:57.613698Z","end":"2026-08-26T00:16:59.316624Z","steps":["trace[1915965911] 'agreement among raft nodes before linearized reading' (duration: 1.702822908s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.316652Z","caller":"traceutil/trace.go:171","msg":"trace[1164007595] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:2448; }","duration":"2.663952427s","start":"2026-08-26T00:16:56.652693Z","end":"2026-08-26T00:16:59.316646Z","steps":["trace[1164007595] 'agreement among raft nodes before linearized reading' (duration: 2.663865515s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.613678Z","time spent":"1.702980731s","remote":"127.0.0.1:59258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.316673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.652678Z","time spent":"2.663989688s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":572,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-08-26T00:16:59.31672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.683465691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.196\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.316755Z","caller":"traceutil/trace.go:171","msg":"trace[389253694] range","detail":"{range_begin:/registry/masterleases/162.253.55.196; range_end:; response_count:0; response_revision:2448; }","duration":"7.683520712s","start":"2026-08-26T00:16:51.633212Z","end":"2026-08-26T00:16:59.316733Z","steps":["trace[389253694] 'agreement among raft nodes before linearized reading' (duration: 7.683488772s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.627496402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:59.316774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.633191Z","time spent":"7.683578764s","remote":"127.0.0.1:58716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.196\" "} {"level":"info","ts":"2026-08-26T00:16:59.316783Z","caller":"traceutil/trace.go:171","msg":"trace[1741589703] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2448; }","duration":"3.627516862s","start":"2026-08-26T00:16:55.689261Z","end":"2026-08-26T00:16:59.316778Z","steps":["trace[1741589703] 'agreement among raft nodes before linearized reading' (duration: 3.627483932s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:55.689202Z","time spent":"3.627595193s","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:16:59.316874Z","caller":"traceutil/trace.go:171","msg":"trace[1085309701] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"1.497854747s","start":"2026-08-26T00:16:57.81901Z","end":"2026-08-26T00:16:59.316865Z","steps":["trace[1085309701] 'process raft request' (duration: 1.497127094s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.598915842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.316914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.202812685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:488"} {"level":"info","ts":"2026-08-26T00:16:59.316932Z","caller":"traceutil/trace.go:171","msg":"trace[1236498238] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2448; }","duration":"8.598984824s","start":"2026-08-26T00:16:50.717941Z","end":"2026-08-26T00:16:59.316926Z","steps":["trace[1236498238] 'agreement among raft nodes before linearized reading' (duration: 8.598943253s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.316933Z","caller":"traceutil/trace.go:171","msg":"trace[1541482667] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:2448; }","duration":"4.202864686s","start":"2026-08-26T00:16:55.114063Z","end":"2026-08-26T00:16:59.316928Z","steps":["trace[1541482667] 'agreement among raft nodes before linearized reading' (duration: 4.202812375s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.316937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.818988Z","time spent":"1.497905217s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.316956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:55.114047Z","time spent":"4.202902576s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":510,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-08-26T00:16:59.316962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.717914Z","time spent":"8.599032405s","remote":"127.0.0.1:59152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.317069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.133440262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.317084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.238651059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.317087Z","caller":"traceutil/trace.go:171","msg":"trace[1165720882] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2448; }","duration":"6.133488523s","start":"2026-08-26T00:16:53.183593Z","end":"2026-08-26T00:16:59.317082Z","steps":["trace[1165720882] 'agreement among raft nodes before linearized reading' (duration: 6.133435882s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.317105Z","caller":"traceutil/trace.go:171","msg":"trace[1909840404] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2448; }","duration":"9.238710079s","start":"2026-08-26T00:16:50.078388Z","end":"2026-08-26T00:16:59.317098Z","steps":["trace[1909840404] 'agreement among raft nodes before linearized reading' (duration: 9.238674918s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.183569Z","time spent":"6.133533944s","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.317108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659991448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.317124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.078367Z","time spent":"9.23875128s","remote":"127.0.0.1:50766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:16:59.317132Z","caller":"traceutil/trace.go:171","msg":"trace[741599253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2448; }","duration":"1.660032068s","start":"2026-08-26T00:16:57.657093Z","end":"2026-08-26T00:16:59.317125Z","steps":["trace[741599253] 'agreement among raft nodes before linearized reading' (duration: 1.659993978s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.657085Z","time spent":"1.660060729s","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.317221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.06922233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.31724Z","caller":"traceutil/trace.go:171","msg":"trace[2146070501] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2448; }","duration":"11.06927643s","start":"2026-08-26T00:16:48.247957Z","end":"2026-08-26T00:16:59.317234Z","steps":["trace[2146070501] 'agreement among raft nodes before linearized reading' (duration: 11.069243s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.247939Z","time spent":"11.069313852s","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.317374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.861219357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.317394Z","caller":"traceutil/trace.go:171","msg":"trace[1224463573] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2448; }","duration":"11.861258688s","start":"2026-08-26T00:16:47.456128Z","end":"2026-08-26T00:16:59.317387Z","steps":["trace[1224463573] 'agreement among raft nodes before linearized reading' (duration: 11.861221746s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.456121Z","time spent":"11.861292089s","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.31752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.229506604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.317597Z","caller":"traceutil/trace.go:171","msg":"trace[1758146732] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2448; }","duration":"12.229634447s","start":"2026-08-26T00:16:47.087957Z","end":"2026-08-26T00:16:59.317591Z","steps":["trace[1758146732] 'agreement among raft nodes before linearized reading' (duration: 12.229541495s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.087924Z","time spent":"12.229686477s","remote":"127.0.0.1:58836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.317711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.847697835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.317774Z","caller":"traceutil/trace.go:171","msg":"trace[1873158173] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2448; }","duration":"12.847793796s","start":"2026-08-26T00:16:46.469966Z","end":"2026-08-26T00:16:59.317759Z","steps":["trace[1873158173] 'agreement among raft nodes before linearized reading' (duration: 12.847677384s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.317812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.469957Z","time spent":"12.847847638s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:02.024742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:02.525218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:03.026249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:03.527197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:04.027505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:04.528411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:05.028779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:05.295897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000386955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:05.295982Z","caller":"traceutil/trace.go:171","msg":"trace[376360895] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000512358s","start":"2026-08-26T00:17:03.295451Z","end":"2026-08-26T00:17:05.295963Z","steps":["trace[376360895] 'agreement among raft nodes before linearized reading' (duration: 2.000403586s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:05.296065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:03.295442Z","time spent":"2.00058809s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:05.529304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:06.030148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:06.330063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.331674Z","time spent":"4.998382239s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:06.431216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.906487904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:06.431273Z","caller":"traceutil/trace.go:171","msg":"trace[1413414789] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"4.906592957s","start":"2026-08-26T00:17:01.524667Z","end":"2026-08-26T00:17:06.43126Z","steps":["trace[1413414789] 'agreement among raft nodes before linearized reading' (duration: 4.906520115s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:06.431306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.524648Z","time spent":"4.906648457s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/26 00:17:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:06.530398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:06.76943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99087621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:06.769511Z","caller":"traceutil/trace.go:171","msg":"trace[835714971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.990976711s","start":"2026-08-26T00:17:01.778516Z","end":"2026-08-26T00:17:06.769493Z","steps":["trace[835714971] 'agreement among raft nodes before linearized reading' (duration: 4.99087296s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:06.76956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.778464Z","time spent":"4.991084393s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:17:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:07.030896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:07.296465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000432203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:07.296578Z","caller":"traceutil/trace.go:171","msg":"trace[247902254] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000550805s","start":"2026-08-26T00:17:05.295979Z","end":"2026-08-26T00:17:07.29653Z","steps":["trace[247902254] 'agreement among raft nodes before linearized reading' (duration: 2.000444543s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:07.296622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.295967Z","time spent":"2.000646258s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:07.399569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999104479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:07.399648Z","caller":"traceutil/trace.go:171","msg":"trace[401044498] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"4.999242721s","start":"2026-08-26T00:17:02.400387Z","end":"2026-08-26T00:17:07.399629Z","steps":["trace[401044498] 'agreement among raft nodes before linearized reading' (duration: 4.999135799s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:07.399732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.400369Z","time spent":"4.999317792s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/08/26 00:17:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:07.532082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:07.85625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999701838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:07.856333Z","caller":"traceutil/trace.go:171","msg":"trace[1235579466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99984363s","start":"2026-08-26T00:17:02.856469Z","end":"2026-08-26T00:17:07.856313Z","steps":["trace[1235579466] 'agreement among raft nodes before linearized reading' (duration: 4.999737498s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:07.856379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.85645Z","time spent":"4.999918972s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:17:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:08.033374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116909519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:08.314691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.982880127s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-26T00:17:08.333229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.046375138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.333333Z","caller":"traceutil/trace.go:171","msg":"trace[434246576] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2483; }","duration":"7.046518151s","start":"2026-08-26T00:17:01.286792Z","end":"2026-08-26T00:17:08.33331Z","steps":["trace[434246576] 'count revisions from in-memory index tree' (duration: 7.046251405s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.333379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.286726Z","time spent":"7.046643144s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.333528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.416328251s","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-08-26T00:17:08.333611Z","caller":"traceutil/trace.go:171","msg":"trace[1612603597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2483; }","duration":"7.416457104s","start":"2026-08-26T00:17:00.917132Z","end":"2026-08-26T00:17:08.333589Z","steps":["trace[1612603597] 'range keys from in-memory index tree' (duration: 7.416192899s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.33364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.307146967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"warn","ts":"2026-08-26T00:17:08.333656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.917114Z","time spent":"7.416531895s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-26T00:17:08.333671Z","caller":"traceutil/trace.go:171","msg":"trace[1365898393] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:2483; }","duration":"7.307215278s","start":"2026-08-26T00:17:01.026447Z","end":"2026-08-26T00:17:08.333662Z","steps":["trace[1365898393] 'range keys from in-memory index tree' (duration: 7.306865062s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.333694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.026419Z","time spent":"7.307269689s","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188274,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-26T00:17:08.333528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.160274739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11666"} {"level":"warn","ts":"2026-08-26T00:17:08.333838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.046973429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.333861Z","caller":"traceutil/trace.go:171","msg":"trace[1209894057] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:2483; }","duration":"7.160657466s","start":"2026-08-26T00:17:01.173193Z","end":"2026-08-26T00:17:08.33385Z","steps":["trace[1209894057] 'range keys from in-memory index tree' (duration: 7.160113376s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:08.333875Z","caller":"traceutil/trace.go:171","msg":"trace[1579600770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2483; }","duration":"7.04703977s","start":"2026-08-26T00:17:01.286825Z","end":"2026-08-26T00:17:08.333865Z","steps":["trace[1579600770] 'range keys from in-memory index tree' (duration: 7.046917108s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.333894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.173179Z","time spent":"7.160704647s","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":11688,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-08-26T00:17:08.333879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.417218437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-26T00:17:08.333935Z","caller":"traceutil/trace.go:171","msg":"trace[1954648423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2483; }","duration":"7.41732252s","start":"2026-08-26T00:17:00.916599Z","end":"2026-08-26T00:17:08.333922Z","steps":["trace[1954648423] 'range keys from in-memory index tree' (duration: 7.417072436s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.333964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.916586Z","time spent":"7.41737202s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:17:08.3339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.286809Z","time spent":"7.047082921s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:08.334059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.021860716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"warn","ts":"2026-08-26T00:17:08.334046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.116641222s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.334093Z","caller":"traceutil/trace.go:171","msg":"trace[393773682] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2483; }","duration":"7.021937728s","start":"2026-08-26T00:17:01.312144Z","end":"2026-08-26T00:17:08.334081Z","steps":["trace[393773682] 'range keys from in-memory index tree' (duration: 7.021743684s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:08.334118Z","caller":"traceutil/trace.go:171","msg":"trace[583920719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2483; }","duration":"6.116716393s","start":"2026-08-26T00:17:02.217394Z","end":"2026-08-26T00:17:08.33411Z","steps":["trace[583920719] 'range keys from in-memory index tree' (duration: 6.116632172s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.334125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.312123Z","time spent":"7.021994198s","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5450,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-26T00:17:08.338097Z","caller":"traceutil/trace.go:171","msg":"trace[537416410] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2585; }","duration":"6.813375742s","start":"2026-08-26T00:17:01.524707Z","end":"2026-08-26T00:17:08.338083Z","steps":["trace[537416410] 'read index received' (duration: 6.790275225s)","trace[537416410] 'applied index is now lower than readState.Index' (duration: 23.099797ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:08.338131Z","caller":"traceutil/trace.go:171","msg":"trace[1651947658] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"1.505733742s","start":"2026-08-26T00:17:06.832381Z","end":"2026-08-26T00:17:08.338115Z","steps":["trace[1651947658] 'process raft request' (duration: 1.505576579s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.33823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.832354Z","time spent":"1.505812273s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:08.338248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.703352443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.196\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:17:08.338272Z","caller":"traceutil/trace.go:171","msg":"trace[255118009] range","detail":"{range_begin:/registry/masterleases/162.253.55.196; range_end:; response_count:1; response_revision:2485; }","duration":"6.703379403s","start":"2026-08-26T00:17:01.634885Z","end":"2026-08-26T00:17:08.338265Z","steps":["trace[255118009] 'agreement among raft nodes before linearized reading' (duration: 6.703288822s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.63486Z","time spent":"6.703427264s","remote":"127.0.0.1:58716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.196\" "} {"level":"warn","ts":"2026-08-26T00:17:08.338281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.254949793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.338378Z","caller":"traceutil/trace.go:171","msg":"trace[405418488] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2485; }","duration":"3.255106646s","start":"2026-08-26T00:17:05.083255Z","end":"2026-08-26T00:17:08.338362Z","steps":["trace[405418488] 'agreement among raft nodes before linearized reading' (duration: 3.254964723s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.083205Z","time spent":"3.255202358s","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.338454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.499819289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.338483Z","caller":"traceutil/trace.go:171","msg":"trace[1804844339] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2485; }","duration":"3.49988701s","start":"2026-08-26T00:17:04.838589Z","end":"2026-08-26T00:17:08.338476Z","steps":["trace[1804844339] 'agreement among raft nodes before linearized reading' (duration: 3.499839739s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:04.838568Z","time spent":"3.499931461s","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.338573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034076225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.338603Z","caller":"traceutil/trace.go:171","msg":"trace[1715897407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2485; }","duration":"1.034149676s","start":"2026-08-26T00:17:07.304445Z","end":"2026-08-26T00:17:08.338595Z","steps":["trace[1715897407] 'agreement among raft nodes before linearized reading' (duration: 1.034099965s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.828338527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:08.33863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:07.304425Z","time spent":"1.034199067s","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:17:08.338648Z","caller":"traceutil/trace.go:171","msg":"trace[1121643197] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2485; }","duration":"3.828406697s","start":"2026-08-26T00:17:04.510235Z","end":"2026-08-26T00:17:08.338641Z","steps":["trace[1121643197] 'agreement among raft nodes before linearized reading' (duration: 3.828362016s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:04.510213Z","time spent":"3.828453069s","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.338776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695081811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.338811Z","caller":"traceutil/trace.go:171","msg":"trace[785092666] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2485; }","duration":"1.695157563s","start":"2026-08-26T00:17:06.643644Z","end":"2026-08-26T00:17:08.338802Z","steps":["trace[785092666] 'agreement among raft nodes before linearized reading' (duration: 1.695102912s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.810854803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-26T00:17:08.338867Z","caller":"traceutil/trace.go:171","msg":"trace[1170062463] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2485; }","duration":"4.810919644s","start":"2026-08-26T00:17:03.527941Z","end":"2026-08-26T00:17:08.33886Z","steps":["trace[1170062463] 'agreement among raft nodes before linearized reading' (duration: 4.810855463s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00686158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"warn","ts":"2026-08-26T00:17:08.33889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:03.52792Z","time spent":"4.810964265s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":546,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-26T00:17:08.338904Z","caller":"traceutil/trace.go:171","msg":"trace[960539414] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:2485; }","duration":"2.006913862s","start":"2026-08-26T00:17:06.331981Z","end":"2026-08-26T00:17:08.338895Z","steps":["trace[960539414] 'agreement among raft nodes before linearized reading' (duration: 2.006874571s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.338948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.331962Z","time spent":"2.006976194s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":572,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-08-26T00:17:08.339028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.048333552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cf32c7e9a67620\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-26T00:17:08.339055Z","caller":"traceutil/trace.go:171","msg":"trace[1336698575] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cf32c7e9a67620; range_end:; response_count:1; response_revision:2485; }","duration":"5.048361332s","start":"2026-08-26T00:17:03.290687Z","end":"2026-08-26T00:17:08.339048Z","steps":["trace[1336698575] 'agreement among raft nodes before linearized reading' (duration: 5.048291331s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.339079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:03.290631Z","time spent":"5.048442274s","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cf32c7e9a67620\" "} {"level":"warn","ts":"2026-08-26T00:17:08.339116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.586214712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.339152Z","caller":"traceutil/trace.go:171","msg":"trace[1832455099] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2485; }","duration":"2.586282693s","start":"2026-08-26T00:17:05.752861Z","end":"2026-08-26T00:17:08.339144Z","steps":["trace[1832455099] 'agreement among raft nodes before linearized reading' (duration: 2.586215082s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.339184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.752846Z","time spent":"2.586329975s","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.339197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.909326129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.339229Z","caller":"traceutil/trace.go:171","msg":"trace[354476419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2485; }","duration":"5.90935902s","start":"2026-08-26T00:17:02.429861Z","end":"2026-08-26T00:17:08.33922Z","steps":["trace[354476419] 'agreement among raft nodes before linearized reading' (duration: 5.909311479s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.339252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.4298Z","time spent":"5.909445721s","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.339329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.199380148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.339361Z","caller":"traceutil/trace.go:171","msg":"trace[2132937325] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2485; }","duration":"3.199442489s","start":"2026-08-26T00:17:05.139911Z","end":"2026-08-26T00:17:08.339353Z","steps":["trace[2132937325] 'agreement among raft nodes before linearized reading' (duration: 3.199388978s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.339368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.690000346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-08-26T00:17:08.339395Z","caller":"traceutil/trace.go:171","msg":"trace[1605843200] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:2485; }","duration":"6.690056027s","start":"2026-08-26T00:17:01.64933Z","end":"2026-08-26T00:17:08.339386Z","steps":["trace[1605843200] 'agreement among raft nodes before linearized reading' (duration: 6.690006666s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.339419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.649315Z","time spent":"6.690099648s","remote":"127.0.0.1:58784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3039,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-08-26T00:17:08.339394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.139893Z","time spent":"3.199492011s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.33884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.643617Z","time spent":"1.695215535s","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.338783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.165794259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.33975Z","caller":"traceutil/trace.go:171","msg":"trace[1944738126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2485; }","duration":"4.166790276s","start":"2026-08-26T00:17:04.17295Z","end":"2026-08-26T00:17:08.339741Z","steps":["trace[1944738126] 'agreement among raft nodes before linearized reading' (duration: 4.165805938s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.33978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:04.172933Z","time spent":"4.166837067s","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:38.322409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.801307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:38.322659Z","caller":"traceutil/trace.go:171","msg":"trace[2083380704] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"140.835213ms","start":"2026-08-26T00:17:38.18179Z","end":"2026-08-26T00:17:38.322625Z","steps":["trace[2083380704] 'process raft request' (duration: 33.749761ms)","trace[2083380704] 'compare' (duration: 106.630074ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:38.322861Z","caller":"traceutil/trace.go:171","msg":"trace[1491914428] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"137.92097ms","start":"2026-08-26T00:17:38.184912Z","end":"2026-08-26T00:17:38.322833Z","steps":["trace[1491914428] 'process raft request' (duration: 137.640595ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:38.579907Z","caller":"traceutil/trace.go:171","msg":"trace[1039905180] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"112.261127ms","start":"2026-08-26T00:17:38.467612Z","end":"2026-08-26T00:17:38.579874Z","steps":["trace[1039905180] 'process raft request' (duration: 60.473484ms)","trace[1039905180] 'compare' (duration: 51.58292ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:38.716071Z","caller":"traceutil/trace.go:171","msg":"trace[114120786] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"130.009335ms","start":"2026-08-26T00:17:38.586026Z","end":"2026-08-26T00:17:38.716035Z","steps":["trace[114120786] 'process raft request' (duration: 93.595945ms)","trace[114120786] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 36.256038ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:40.698794Z","caller":"traceutil/trace.go:171","msg":"trace[1671275001] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"113.937638ms","start":"2026-08-26T00:17:40.584819Z","end":"2026-08-26T00:17:40.698756Z","steps":["trace[1671275001] 'process raft request' (duration: 26.414296ms)","trace[1671275001] 'compare' (duration: 87.321848ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:50.164996Z","caller":"traceutil/trace.go:171","msg":"trace[1457875720] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"159.212171ms","start":"2026-08-26T00:17:50.005758Z","end":"2026-08-26T00:17:50.16497Z","steps":["trace[1457875720] 'process raft request' (duration: 159.053187ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:17.085631Z","caller":"traceutil/trace.go:171","msg":"trace[462071849] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"104.90285ms","start":"2026-08-26T00:18:16.98068Z","end":"2026-08-26T00:18:17.085583Z","steps":["trace[462071849] 'process raft request' (duration: 104.692846ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:17.08637Z","caller":"traceutil/trace.go:171","msg":"trace[1364447607] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"101.903266ms","start":"2026-08-26T00:18:16.984432Z","end":"2026-08-26T00:18:17.086335Z","steps":["trace[1364447607] 'process raft request' (duration: 101.623921ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:17.086377Z","caller":"traceutil/trace.go:171","msg":"trace[587969784] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"100.063102ms","start":"2026-08-26T00:18:16.986226Z","end":"2026-08-26T00:18:17.086289Z","steps":["trace[587969784] 'process raft request' (duration: 99.97987ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:22.792161Z","caller":"traceutil/trace.go:171","msg":"trace[322054804] linearizableReadLoop","detail":"{readStateIndex:3102; appliedIndex:3101; }","duration":"361.795638ms","start":"2026-08-26T00:18:22.430341Z","end":"2026-08-26T00:18:22.792137Z","steps":["trace[322054804] 'read index received' (duration: 361.667655ms)","trace[322054804] 'applied index is now lower than readState.Index' (duration: 126.953µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:18:22.792293Z","caller":"traceutil/trace.go:171","msg":"trace[332725981] transaction","detail":"{read_only:false; response_revision:2978; number_of_response:1; }","duration":"425.0112ms","start":"2026-08-26T00:18:22.36727Z","end":"2026-08-26T00:18:22.792281Z","steps":["trace[332725981] 'process raft request' (duration: 424.707025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:22.792405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:18:22.36725Z","time spent":"425.071831ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:18:22.792556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.213966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:18:22.792598Z","caller":"traceutil/trace.go:171","msg":"trace[630314179] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2978; }","duration":"362.328368ms","start":"2026-08-26T00:18:22.430257Z","end":"2026-08-26T00:18:22.792586Z","steps":["trace[630314179] 'agreement among raft nodes before linearized reading' (duration: 362.265487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:22.792627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:18:22.430225Z","time spent":"362.394629ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:18:22.792907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.82027ms","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-08-26T00:18:22.792984Z","caller":"traceutil/trace.go:171","msg":"trace[675574096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2978; }","duration":"166.967073ms","start":"2026-08-26T00:18:22.626Z","end":"2026-08-26T00:18:22.792967Z","steps":["trace[675574096] 'agreement among raft nodes before linearized reading' (duration: 166.736589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:22.793098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.462904ms","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-08-26T00:18:22.793223Z","caller":"traceutil/trace.go:171","msg":"trace[753501608] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2978; }","duration":"153.622377ms","start":"2026-08-26T00:18:22.639577Z","end":"2026-08-26T00:18:22.793199Z","steps":["trace[753501608] 'agreement among raft nodes before linearized reading' (duration: 153.2487ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:22.933914Z","caller":"traceutil/trace.go:171","msg":"trace[1937100048] transaction","detail":"{read_only:false; response_revision:2979; number_of_response:1; }","duration":"135.683496ms","start":"2026-08-26T00:18:22.798196Z","end":"2026-08-26T00:18:22.93388Z","steps":["trace[1937100048] 'process raft request' (duration: 133.120429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:22.934113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.68694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-08-26T00:18:22.934157Z","caller":"traceutil/trace.go:171","msg":"trace[1068884652] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2980; }","duration":"133.762122ms","start":"2026-08-26T00:18:22.800385Z","end":"2026-08-26T00:18:22.934147Z","steps":["trace[1068884652] 'agreement among raft nodes before linearized reading' (duration: 133.590029ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:22.933934Z","caller":"traceutil/trace.go:171","msg":"trace[1090189188] linearizableReadLoop","detail":"{readStateIndex:3103; appliedIndex:3102; }","duration":"133.510387ms","start":"2026-08-26T00:18:22.800404Z","end":"2026-08-26T00:18:22.933915Z","steps":["trace[1090189188] 'read index received' (duration: 131.199144ms)","trace[1090189188] 'applied index is now lower than readState.Index' (duration: 2.310093ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:19:36.76253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116911962,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:19:36.797157Z","caller":"traceutil/trace.go:171","msg":"trace[1213936503] linearizableReadLoop","detail":"{readStateIndex:3504; appliedIndex:3503; }","duration":"535.225293ms","start":"2026-08-26T00:19:36.261902Z","end":"2026-08-26T00:19:36.797127Z","steps":["trace[1213936503] 'read index received' (duration: 534.732384ms)","trace[1213936503] 'applied index is now lower than readState.Index' (duration: 491.319µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:19:36.79734Z","caller":"traceutil/trace.go:171","msg":"trace[500432404] transaction","detail":"{read_only:false; response_revision:3365; number_of_response:1; }","duration":"650.530074ms","start":"2026-08-26T00:19:36.146793Z","end":"2026-08-26T00:19:36.797323Z","steps":["trace[500432404] 'process raft request' (duration: 649.898042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:36.797469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:36.146768Z","time spent":"650.599284ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:19:36.798533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.333918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-26T00:19:36.798536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.521324ms","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-08-26T00:19:36.798583Z","caller":"traceutil/trace.go:171","msg":"trace[1819362956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3365; }","duration":"321.4256ms","start":"2026-08-26T00:19:36.477145Z","end":"2026-08-26T00:19:36.798571Z","steps":["trace[1819362956] 'agreement among raft nodes before linearized reading' (duration: 321.266277ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:36.798594Z","caller":"traceutil/trace.go:171","msg":"trace[997745058] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3365; }","duration":"159.633945ms","start":"2026-08-26T00:19:36.638946Z","end":"2026-08-26T00:19:36.79858Z","steps":["trace[997745058] 'agreement among raft nodes before linearized reading' (duration: 159.460312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:36.798619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:36.477123Z","time spent":"321.488422ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-26T00:19:36.798601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.590619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:19:36.798678Z","caller":"traceutil/trace.go:171","msg":"trace[639534679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3365; }","duration":"511.817252ms","start":"2026-08-26T00:19:36.286841Z","end":"2026-08-26T00:19:36.798658Z","steps":["trace[639534679] 'agreement among raft nodes before linearized reading' (duration: 510.578259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:36.798828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.905464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:19:36.799112Z","caller":"traceutil/trace.go:171","msg":"trace[57817156] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3365; }","duration":"537.24984ms","start":"2026-08-26T00:19:36.261839Z","end":"2026-08-26T00:19:36.799089Z","steps":["trace[57817156] 'agreement among raft nodes before linearized reading' (duration: 536.7247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:36.799203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:36.261815Z","time spent":"537.372442ms","remote":"127.0.0.1:58942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:19:36.798977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:36.286824Z","time spent":"511.895314ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:21:27.787614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116913788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:21:28.223068Z","caller":"traceutil/trace.go:171","msg":"trace[566511037] transaction","detail":"{read_only:false; response_revision:4108; number_of_response:1; }","duration":"976.184769ms","start":"2026-08-26T00:21:27.246856Z","end":"2026-08-26T00:21:28.223041Z","steps":["trace[566511037] 'process raft request' (duration: 975.976755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.223236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.246834Z","time spent":"976.300501ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:21:28.26646Z","caller":"traceutil/trace.go:171","msg":"trace[1429744897] linearizableReadLoop","detail":"{readStateIndex:4280; appliedIndex:4278; }","duration":"979.376318ms","start":"2026-08-26T00:21:27.287068Z","end":"2026-08-26T00:21:28.266444Z","steps":["trace[1429744897] 'read index received' (duration: 935.652963ms)","trace[1429744897] 'applied index is now lower than readState.Index' (duration: 43.722735ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:28.266638Z","caller":"traceutil/trace.go:171","msg":"trace[492580358] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"624.866279ms","start":"2026-08-26T00:21:27.641764Z","end":"2026-08-26T00:21:28.26663Z","steps":["trace[492580358] 'process raft request' (duration: 623.628897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.267538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.641739Z","time spent":"624.92601ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:21:28.267765Z","caller":"traceutil/trace.go:171","msg":"trace[241106632] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"165.820339ms","start":"2026-08-26T00:21:28.101936Z","end":"2026-08-26T00:21:28.267756Z","steps":["trace[241106632] 'process raft request' (duration: 164.478084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.267917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.847275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:21:28.267989Z","caller":"traceutil/trace.go:171","msg":"trace[2030449147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4110; }","duration":"980.953956ms","start":"2026-08-26T00:21:27.287028Z","end":"2026-08-26T00:21:28.267982Z","steps":["trace[2030449147] 'agreement among raft nodes before linearized reading' (duration: 980.832504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.268011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.287009Z","time spent":"980.996128ms","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:21:28.268232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.700321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:21:28.268263Z","caller":"traceutil/trace.go:171","msg":"trace[1541596391] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4110; }","duration":"714.731671ms","start":"2026-08-26T00:21:27.553522Z","end":"2026-08-26T00:21:28.268254Z","steps":["trace[1541596391] 'agreement among raft nodes before linearized reading' (duration: 714.68806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.268354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.553473Z","time spent":"714.805093ms","remote":"127.0.0.1:58942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:21:28.268889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.080164ms","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-08-26T00:21:28.268918Z","caller":"traceutil/trace.go:171","msg":"trace[1665061750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4110; }","duration":"259.126165ms","start":"2026-08-26T00:21:28.009785Z","end":"2026-08-26T00:21:28.268911Z","steps":["trace[1665061750] 'agreement among raft nodes before linearized reading' (duration: 259.063494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.269165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.240217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:21:28.269706Z","caller":"traceutil/trace.go:171","msg":"trace[1834469104] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4110; }","duration":"506.809518ms","start":"2026-08-26T00:21:27.762886Z","end":"2026-08-26T00:21:28.269695Z","steps":["trace[1834469104] 'agreement among raft nodes before linearized reading' (duration: 506.234528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.269735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.762873Z","time spent":"506.849429ms","remote":"127.0.0.1:59242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:21:28.270111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.721122ms","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-08-26T00:21:28.27015Z","caller":"traceutil/trace.go:171","msg":"trace[352775616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4110; }","duration":"403.788784ms","start":"2026-08-26T00:21:27.866354Z","end":"2026-08-26T00:21:28.270143Z","steps":["trace[352775616] 'agreement among raft nodes before linearized reading' (duration: 402.713955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:28.270169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:27.866332Z","time spent":"403.832685ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:14.260994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.273441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-26T00:22:14.26155Z","caller":"traceutil/trace.go:171","msg":"trace[1805846953] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4886; }","duration":"133.872932ms","start":"2026-08-26T00:22:14.127646Z","end":"2026-08-26T00:22:14.261519Z","steps":["trace[1805846953] 'range keys from in-memory index tree' (duration: 133.081317ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:14.498765Z","caller":"traceutil/trace.go:171","msg":"trace[1876026434] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"100.869195ms","start":"2026-08-26T00:22:14.397857Z","end":"2026-08-26T00:22:14.498726Z","steps":["trace[1876026434] 'process raft request' (duration: 100.660341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.724149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.150235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4b67d98-b93f-42fb-9480-76a6da2e829c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-26T00:22:20.724281Z","caller":"traceutil/trace.go:171","msg":"trace[1951432049] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4b67d98-b93f-42fb-9480-76a6da2e829c; range_end:; response_count:1; response_revision:4993; }","duration":"203.348339ms","start":"2026-08-26T00:22:20.520907Z","end":"2026-08-26T00:22:20.724255Z","steps":["trace[1951432049] 'range keys from in-memory index tree' (duration: 202.822219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.724267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.009496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:22:20.724363Z","caller":"traceutil/trace.go:171","msg":"trace[159805333] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"202.956432ms","start":"2026-08-26T00:22:20.521388Z","end":"2026-08-26T00:22:20.724344Z","steps":["trace[159805333] 'process raft request' (duration: 88.757012ms)","trace[159805333] 'compare' (duration: 113.722711ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:22:20.945423Z","caller":"traceutil/trace.go:171","msg":"trace[1329786433] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"374.399764ms","start":"2026-08-26T00:22:20.570989Z","end":"2026-08-26T00:22:20.945389Z","steps":["trace[1329786433] 'process raft request' (duration: 353.150213ms)","trace[1329786433] 'compare' (duration: 21.026867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:22:20.946019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.876028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:22:20.946099Z","caller":"traceutil/trace.go:171","msg":"trace[678609065] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4997; }","duration":"365.01617ms","start":"2026-08-26T00:22:20.581065Z","end":"2026-08-26T00:22:20.946081Z","steps":["trace[678609065] 'agreement among raft nodes before linearized reading' (duration: 364.853357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.946178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.581048Z","time spent":"365.117233ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:22:20.946295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.57097Z","time spent":"375.080906ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:20.945717Z","caller":"traceutil/trace.go:171","msg":"trace[707730202] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"367.15446ms","start":"2026-08-26T00:22:20.578545Z","end":"2026-08-26T00:22:20.945699Z","steps":["trace[707730202] 'process raft request' (duration: 366.791744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.946778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.578519Z","time spent":"368.196629ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:20.945835Z","caller":"traceutil/trace.go:171","msg":"trace[184033141] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5196; }","duration":"364.673025ms","start":"2026-08-26T00:22:20.581102Z","end":"2026-08-26T00:22:20.945775Z","steps":["trace[184033141] 'read index received' (duration: 30.015873ms)","trace[184033141] 'applied index is now lower than readState.Index' (duration: 334.644691ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:22:20.94585Z","caller":"traceutil/trace.go:171","msg":"trace[1314004958] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"191.111843ms","start":"2026-08-26T00:22:20.754705Z","end":"2026-08-26T00:22:20.945817Z","steps":["trace[1314004958] 'process raft request' (duration: 190.969661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.947121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.641965ms","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-08-26T00:22:20.94717Z","caller":"traceutil/trace.go:171","msg":"trace[1905275277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4997; }","duration":"332.723927ms","start":"2026-08-26T00:22:20.614432Z","end":"2026-08-26T00:22:20.947156Z","steps":["trace[1905275277] 'agreement among raft nodes before linearized reading' (duration: 332.519073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.947207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.614417Z","time spent":"332.782097ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:22:20.947208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.661536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T00:22:20.947269Z","caller":"traceutil/trace.go:171","msg":"trace[1574619568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4997; }","duration":"332.799989ms","start":"2026-08-26T00:22:20.614458Z","end":"2026-08-26T00:22:20.947258Z","steps":["trace[1574619568] 'agreement among raft nodes before linearized reading' (duration: 332.604284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.947346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.9869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-26T00:22:20.947447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.931311ms","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-08-26T00:22:20.947532Z","caller":"traceutil/trace.go:171","msg":"trace[852581390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4997; }","duration":"333.147715ms","start":"2026-08-26T00:22:20.614368Z","end":"2026-08-26T00:22:20.947516Z","steps":["trace[852581390] 'agreement among raft nodes before linearized reading' (duration: 332.943451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.947575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.614346Z","time spent":"333.219756ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:20.947371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.614447Z","time spent":"332.91435ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-26T00:22:20.947593Z","caller":"traceutil/trace.go:171","msg":"trace[2063545848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4997; }","duration":"328.262737ms","start":"2026-08-26T00:22:20.619161Z","end":"2026-08-26T00:22:20.947424Z","steps":["trace[2063545848] 'agreement among raft nodes before linearized reading' (duration: 327.873858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:20.947688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:20.619155Z","time spent":"328.515051ms","remote":"127.0.0.1:58982","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-08-26T00:22:21.338077Z","caller":"traceutil/trace.go:171","msg":"trace[152297410] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"230.715532ms","start":"2026-08-26T00:22:21.107319Z","end":"2026-08-26T00:22:21.338034Z","steps":["trace[152297410] 'process raft request' (duration: 144.394765ms)","trace[152297410] 'compare' (duration: 86.107033ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:22:24.305931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.300183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-26T00:22:24.306029Z","caller":"traceutil/trace.go:171","msg":"trace[659230757] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5044; }","duration":"162.431725ms","start":"2026-08-26T00:22:24.143574Z","end":"2026-08-26T00:22:24.306006Z","steps":["trace[659230757] 'range keys from in-memory index tree' (duration: 162.13405ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:28.622893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2586} {"level":"info","ts":"2026-08-26T00:22:28.70169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2586,"took":"76.448376ms","hash":1700435184,"current-db-size-bytes":52924416,"current-db-size":"53 MB","current-db-size-in-use-bytes":27672576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-26T00:22:28.701772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1700435184,"revision":2586,"compact-revision":-1} {"level":"info","ts":"2026-08-26T00:22:34.791712Z","caller":"traceutil/trace.go:171","msg":"trace[273745882] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"277.502272ms","start":"2026-08-26T00:22:34.514188Z","end":"2026-08-26T00:22:34.79169Z","steps":["trace[273745882] 'process raft request' (duration: 277.3572ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:34.792033Z","caller":"traceutil/trace.go:171","msg":"trace[1578550106] linearizableReadLoop","detail":"{readStateIndex:5391; appliedIndex:5391; }","duration":"276.581015ms","start":"2026-08-26T00:22:34.515444Z","end":"2026-08-26T00:22:34.792025Z","steps":["trace[1578550106] 'read index received' (duration: 276.577425ms)","trace[1578550106] 'applied index is now lower than readState.Index' (duration: 3.28µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:22:34.792204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.737128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-08-26T00:22:34.792231Z","caller":"traceutil/trace.go:171","msg":"trace[1774165304] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5180; }","duration":"276.806419ms","start":"2026-08-26T00:22:34.515419Z","end":"2026-08-26T00:22:34.792226Z","steps":["trace[1774165304] 'agreement among raft nodes before linearized reading' (duration: 276.654536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:35.292129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116916130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:22:35.793221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18200629646116916130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:22:36.17304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381051902s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:22:36.173565Z","caller":"traceutil/trace.go:171","msg":"trace[1569654889] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"1.394849585s","start":"2026-08-26T00:22:34.778685Z","end":"2026-08-26T00:22:36.173534Z","steps":["trace[1569654889] 'process raft request' (duration: 1.394618511s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.173781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:34.77866Z","time spent":"1.394996698s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:36.25472Z","caller":"traceutil/trace.go:171","msg":"trace[1716702512] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5391; }","duration":"1.462600581s","start":"2026-08-26T00:22:34.792054Z","end":"2026-08-26T00:22:36.254655Z","steps":["trace[1716702512] 'read index received' (duration: 1.381253355s)","trace[1716702512] 'applied index is now lower than readState.Index' (duration: 81.345585ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:22:36.254778Z","caller":"traceutil/trace.go:171","msg":"trace[437509615] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"1.239200444s","start":"2026-08-26T00:22:35.015532Z","end":"2026-08-26T00:22:36.254732Z","steps":["trace[437509615] 'process raft request' (duration: 1.238906009s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.254946Z","caller":"traceutil/trace.go:171","msg":"trace[1606323090] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"928.688395ms","start":"2026-08-26T00:22:35.326209Z","end":"2026-08-26T00:22:36.254897Z","steps":["trace[1606323090] 'process raft request' (duration: 928.351139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.25496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.015502Z","time spent":"1.239368647s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:36.25502Z","caller":"traceutil/trace.go:171","msg":"trace[1303741408] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"1.247356623s","start":"2026-08-26T00:22:35.00764Z","end":"2026-08-26T00:22:36.254997Z","steps":["trace[1303741408] 'process raft request' (duration: 1.211152787s)","trace[1303741408] 'compare' (duration: 35.248238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:22:36.255157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.326184Z","time spent":"928.854767ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:22:36.255228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694436813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-745d8dcbf7-jzjxh\" ","response":"range_response_count:1 size:11842"} {"level":"warn","ts":"2026-08-26T00:22:36.255267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.007608Z","time spent":"1.247518286s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:36.25536Z","caller":"traceutil/trace.go:171","msg":"trace[1254872615] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-745d8dcbf7-jzjxh; range_end:; response_count:1; response_revision:5185; }","duration":"1.694644657s","start":"2026-08-26T00:22:34.56068Z","end":"2026-08-26T00:22:36.255324Z","steps":["trace[1254872615] 'agreement among raft nodes before linearized reading' (duration: 1.69428669s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.255435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.097572ms","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":"warn","ts":"2026-08-26T00:22:36.255459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:34.560654Z","time spent":"1.694776139s","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11864,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-745d8dcbf7-jzjxh\" "} {"level":"warn","ts":"2026-08-26T00:22:36.255466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.290812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-26T00:22:36.255487Z","caller":"traceutil/trace.go:171","msg":"trace[1383847812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5185; }","duration":"929.150753ms","start":"2026-08-26T00:22:35.326324Z","end":"2026-08-26T00:22:36.255475Z","steps":["trace[1383847812] 'agreement among raft nodes before linearized reading' (duration: 928.9758ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.255514Z","caller":"traceutil/trace.go:171","msg":"trace[1327900822] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5185; }","duration":"925.345533ms","start":"2026-08-26T00:22:35.33015Z","end":"2026-08-26T00:22:36.255495Z","steps":["trace[1327900822] 'agreement among raft nodes before linearized reading' (duration: 925.17477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.255482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459104647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-08-26T00:22:36.255551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.330145Z","time spent":"925.398314ms","remote":"127.0.0.1:58852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-26T00:22:36.255584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.758179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:22:36.255614Z","caller":"traceutil/trace.go:171","msg":"trace[971224016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5185; }","duration":"926.79963ms","start":"2026-08-26T00:22:35.328805Z","end":"2026-08-26T00:22:36.255605Z","steps":["trace[971224016] 'agreement among raft nodes before linearized reading' (duration: 926.754539ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.2556Z","caller":"traceutil/trace.go:171","msg":"trace[776679736] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5185; }","duration":"1.45931198s","start":"2026-08-26T00:22:34.796248Z","end":"2026-08-26T00:22:36.25556Z","steps":["trace[776679736] 'agreement among raft nodes before linearized reading' (duration: 1.458949943s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.255717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.361227ms","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-08-26T00:22:36.255751Z","caller":"traceutil/trace.go:171","msg":"trace[417616510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5185; }","duration":"929.454039ms","start":"2026-08-26T00:22:35.326288Z","end":"2026-08-26T00:22:36.255743Z","steps":["trace[417616510] 'agreement among raft nodes before linearized reading' (duration: 929.369978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.255776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.326279Z","time spent":"929.49248ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:36.2557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:34.796224Z","time spent":"1.459455503s","remote":"127.0.0.1:58852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-26T00:22:36.255536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.326281Z","time spent":"929.247605ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:36.255641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.328798Z","time spent":"926.83871ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:22:36.256058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445091629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-08-26T00:22:36.256067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.17258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-26T00:22:36.256109Z","caller":"traceutil/trace.go:171","msg":"trace[1896238215] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5185; }","duration":"1.445131739s","start":"2026-08-26T00:22:34.810954Z","end":"2026-08-26T00:22:36.256086Z","steps":["trace[1896238215] 'agreement among raft nodes before linearized reading' (duration: 1.445012717s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.256122Z","caller":"traceutil/trace.go:171","msg":"trace[985984441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5185; }","duration":"869.253242ms","start":"2026-08-26T00:22:35.386856Z","end":"2026-08-26T00:22:36.256109Z","steps":["trace[985984441] 'agreement among raft nodes before linearized reading' (duration: 868.717382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.256147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:34.810868Z","time spent":"1.445272132s","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-26T00:22:36.25616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.386844Z","time spent":"869.307982ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-26T00:22:36.256285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179668479s","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-08-26T00:22:36.256306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459019784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18cf33145713084b\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-08-26T00:22:36.256363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238765085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:22:36.256387Z","caller":"traceutil/trace.go:171","msg":"trace[1559183518] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18cf33145713084b; range_end:; response_count:1; response_revision:5185; }","duration":"1.459113215s","start":"2026-08-26T00:22:34.797254Z","end":"2026-08-26T00:22:36.256368Z","steps":["trace[1559183518] 'agreement among raft nodes before linearized reading' (duration: 1.45883279s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.256415Z","caller":"traceutil/trace.go:171","msg":"trace[1976436679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5185; }","duration":"1.238847997s","start":"2026-08-26T00:22:35.017538Z","end":"2026-08-26T00:22:36.256386Z","steps":["trace[1976436679] 'agreement among raft nodes before linearized reading' (duration: 1.238755965s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.25642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.142641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-26T00:22:36.256439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:34.797248Z","time spent":"1.459180016s","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":762,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18cf33145713084b\" "} {"level":"warn","ts":"2026-08-26T00:22:36.256449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.017511Z","time spent":"1.238929158s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:22:36.256452Z","caller":"traceutil/trace.go:171","msg":"trace[258997546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5185; }","duration":"930.212553ms","start":"2026-08-26T00:22:35.32623Z","end":"2026-08-26T00:22:36.256443Z","steps":["trace[258997546] 'agreement among raft nodes before linearized reading' (duration: 930.127311ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.256333Z","caller":"traceutil/trace.go:171","msg":"trace[1903863335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5185; }","duration":"1.179778051s","start":"2026-08-26T00:22:35.076544Z","end":"2026-08-26T00:22:36.256322Z","steps":["trace[1903863335] 'agreement among raft nodes before linearized reading' (duration: 1.179650908s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.256499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.326207Z","time spent":"930.284594ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:36.256585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.076513Z","time spent":"1.180012625s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-26T00:22:36.254923Z","caller":"traceutil/trace.go:171","msg":"trace[747411265] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"833.832411ms","start":"2026-08-26T00:22:35.42104Z","end":"2026-08-26T00:22:36.254873Z","steps":["trace[747411265] 'process raft request' (duration: 833.564437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.260052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:35.421014Z","time spent":"835.867949ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:22:36.416695Z","caller":"traceutil/trace.go:171","msg":"trace[1034389269] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"152.882171ms","start":"2026-08-26T00:22:36.263775Z","end":"2026-08-26T00:22:36.416657Z","steps":["trace[1034389269] 'process raft request' (duration: 152.590756ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.417268Z","caller":"traceutil/trace.go:171","msg":"trace[1217422095] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"152.943083ms","start":"2026-08-26T00:22:36.264308Z","end":"2026-08-26T00:22:36.417251Z","steps":["trace[1217422095] 'process raft request' (duration: 152.24975ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.418662Z","caller":"traceutil/trace.go:171","msg":"trace[909490289] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"154.260796ms","start":"2026-08-26T00:22:36.264377Z","end":"2026-08-26T00:22:36.418638Z","steps":["trace[909490289] 'process raft request' (duration: 152.229239ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.41898Z","caller":"traceutil/trace.go:171","msg":"trace[2081337377] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"154.540991ms","start":"2026-08-26T00:22:36.26442Z","end":"2026-08-26T00:22:36.418961Z","steps":["trace[2081337377] 'process raft request' (duration: 154.120714ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.419458Z","caller":"traceutil/trace.go:171","msg":"trace[2059606877] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"152.83924ms","start":"2026-08-26T00:22:36.266603Z","end":"2026-08-26T00:22:36.419439Z","steps":["trace[2059606877] 'process raft request' (duration: 152.285499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.41965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.816395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:22:36.419651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.343596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-08-26T00:22:36.419699Z","caller":"traceutil/trace.go:171","msg":"trace[27602971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5192; }","duration":"155.925698ms","start":"2026-08-26T00:22:36.263761Z","end":"2026-08-26T00:22:36.419687Z","steps":["trace[27602971] 'agreement among raft nodes before linearized reading' (duration: 155.821356ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.419724Z","caller":"traceutil/trace.go:171","msg":"trace[599528780] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5192; }","duration":"149.443998ms","start":"2026-08-26T00:22:36.27026Z","end":"2026-08-26T00:22:36.419704Z","steps":["trace[599528780] 'agreement among raft nodes before linearized reading' (duration: 149.233144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.420571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.997306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8da87da0-c415-4a6a-ae44-b5d8267becd4\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-26T00:22:36.420611Z","caller":"traceutil/trace.go:171","msg":"trace[945066455] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"152.364881ms","start":"2026-08-26T00:22:36.268234Z","end":"2026-08-26T00:22:36.420599Z","steps":["trace[945066455] 'process raft request' (duration: 151.008167ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.420636Z","caller":"traceutil/trace.go:171","msg":"trace[446657125] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8da87da0-c415-4a6a-ae44-b5d8267becd4; range_end:; response_count:1; response_revision:5192; }","duration":"157.075118ms","start":"2026-08-26T00:22:36.263542Z","end":"2026-08-26T00:22:36.420617Z","steps":["trace[446657125] 'agreement among raft nodes before linearized reading' (duration: 156.928706ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.471503Z","caller":"traceutil/trace.go:171","msg":"trace[1874039396] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"120.247781ms","start":"2026-08-26T00:22:36.351226Z","end":"2026-08-26T00:22:36.471474Z","steps":["trace[1874039396] 'process raft request' (duration: 120.117439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.471744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.178594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11939"} {"level":"info","ts":"2026-08-26T00:22:36.471832Z","caller":"traceutil/trace.go:171","msg":"trace[1022566515] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5193; }","duration":"129.360648ms","start":"2026-08-26T00:22:36.342454Z","end":"2026-08-26T00:22:36.471815Z","steps":["trace[1022566515] 'agreement among raft nodes before linearized reading' (duration: 129.099864ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.623657Z","caller":"traceutil/trace.go:171","msg":"trace[125363573] linearizableReadLoop","detail":"{readStateIndex:5407; appliedIndex:5404; }","duration":"152.119377ms","start":"2026-08-26T00:22:36.471518Z","end":"2026-08-26T00:22:36.623637Z","steps":["trace[125363573] 'read index received' (duration: 151.875212ms)","trace[125363573] 'applied index is now lower than readState.Index' (duration: 243.435µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:22:36.623662Z","caller":"traceutil/trace.go:171","msg":"trace[166425618] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"195.464283ms","start":"2026-08-26T00:22:36.428165Z","end":"2026-08-26T00:22:36.623629Z","steps":["trace[166425618] 'process raft request' (duration: 195.235478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.623851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.477743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18cf33145817f1c9\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-08-26T00:22:36.623791Z","caller":"traceutil/trace.go:171","msg":"trace[1972777792] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"191.839597ms","start":"2026-08-26T00:22:36.431898Z","end":"2026-08-26T00:22:36.623737Z","steps":["trace[1972777792] 'process raft request' (duration: 191.697074ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.623858Z","caller":"traceutil/trace.go:171","msg":"trace[633489599] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"193.635001ms","start":"2026-08-26T00:22:36.430181Z","end":"2026-08-26T00:22:36.623816Z","steps":["trace[633489599] 'process raft request' (duration: 193.366565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.623915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.73655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:22:36.623939Z","caller":"traceutil/trace.go:171","msg":"trace[1284673250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5196; }","duration":"194.767991ms","start":"2026-08-26T00:22:36.429168Z","end":"2026-08-26T00:22:36.623936Z","steps":["trace[1284673250] 'agreement among raft nodes before linearized reading' (duration: 194.73221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.623996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.139628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17235"} {"level":"info","ts":"2026-08-26T00:22:36.62388Z","caller":"traceutil/trace.go:171","msg":"trace[1657000842] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18cf33145817f1c9; range_end:; response_count:1; response_revision:5196; }","duration":"196.577425ms","start":"2026-08-26T00:22:36.427295Z","end":"2026-08-26T00:22:36.623873Z","steps":["trace[1657000842] 'agreement among raft nodes before linearized reading' (duration: 196.439702ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.624078Z","caller":"traceutil/trace.go:171","msg":"trace[582604307] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5196; }","duration":"195.28328ms","start":"2026-08-26T00:22:36.428776Z","end":"2026-08-26T00:22:36.624059Z","steps":["trace[582604307] 'agreement among raft nodes before linearized reading' (duration: 194.971454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.6241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.83006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-26T00:22:36.624141Z","caller":"traceutil/trace.go:171","msg":"trace[1707780245] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5196; }","duration":"196.914841ms","start":"2026-08-26T00:22:36.427216Z","end":"2026-08-26T00:22:36.62413Z","steps":["trace[1707780245] 'agreement among raft nodes before linearized reading' (duration: 196.82025ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.775487Z","caller":"traceutil/trace.go:171","msg":"trace[413878548] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"135.858868ms","start":"2026-08-26T00:22:36.639572Z","end":"2026-08-26T00:22:36.775431Z","steps":["trace[413878548] 'process raft request' (duration: 135.42042ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:36.775878Z","caller":"traceutil/trace.go:171","msg":"trace[910289056] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"135.138575ms","start":"2026-08-26T00:22:36.640713Z","end":"2026-08-26T00:22:36.775852Z","steps":["trace[910289056] 'process raft request' (duration: 134.504913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:36.776194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.732263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:22:36.776281Z","caller":"traceutil/trace.go:171","msg":"trace[75180377] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5199; }","duration":"144.262523ms","start":"2026-08-26T00:22:36.632004Z","end":"2026-08-26T00:22:36.776267Z","steps":["trace[75180377] 'agreement among raft nodes before linearized reading' (duration: 143.703913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:00.692357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.098103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-26T00:23:00.692451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.801436ms","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-08-26T00:23:00.692569Z","caller":"traceutil/trace.go:171","msg":"trace[1335833772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5343; }","duration":"117.964349ms","start":"2026-08-26T00:23:00.574574Z","end":"2026-08-26T00:23:00.692538Z","steps":["trace[1335833772] 'range keys from in-memory index tree' (duration: 117.604522ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:00.692494Z","caller":"traceutil/trace.go:171","msg":"trace[377945885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5343; }","duration":"123.280556ms","start":"2026-08-26T00:23:00.569187Z","end":"2026-08-26T00:23:00.692468Z","steps":["trace[377945885] 'range keys from in-memory index tree' (duration: 122.970911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:00.693258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.726998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T00:23:00.69332Z","caller":"traceutil/trace.go:171","msg":"trace[1062092863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5343; }","duration":"121.83225ms","start":"2026-08-26T00:23:00.571473Z","end":"2026-08-26T00:23:00.693306Z","steps":["trace[1062092863] 'range keys from in-memory index tree' (duration: 121.493075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:00.693387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.951749ms","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-08-26T00:23:00.693458Z","caller":"traceutil/trace.go:171","msg":"trace[526683336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5343; }","duration":"118.075131ms","start":"2026-08-26T00:23:00.575367Z","end":"2026-08-26T00:23:00.693442Z","steps":["trace[526683336] 'range keys from in-memory index tree' (duration: 117.085551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:00.693467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.804403ms","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-08-26T00:23:00.693555Z","caller":"traceutil/trace.go:171","msg":"trace[166548253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5343; }","duration":"119.914885ms","start":"2026-08-26T00:23:00.573624Z","end":"2026-08-26T00:23:00.693539Z","steps":["trace[166548253] 'range keys from in-memory index tree' (duration: 119.63989ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:32.120988Z","caller":"traceutil/trace.go:171","msg":"trace[1433816503] linearizableReadLoop","detail":"{readStateIndex:7128; appliedIndex:7127; }","duration":"101.661634ms","start":"2026-08-26T00:26:32.019294Z","end":"2026-08-26T00:26:32.120956Z","steps":["trace[1433816503] 'read index received' (duration: 101.443102ms)","trace[1433816503] 'applied index is now lower than readState.Index' (duration: 216.802µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:26:32.121162Z","caller":"traceutil/trace.go:171","msg":"trace[285370625] transaction","detail":"{read_only:false; response_revision:6860; number_of_response:1; }","duration":"115.133527ms","start":"2026-08-26T00:26:32.006016Z","end":"2026-08-26T00:26:32.121149Z","steps":["trace[285370625] 'process raft request' (duration: 114.719453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:26:32.121595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.274791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:26:32.121697Z","caller":"traceutil/trace.go:171","msg":"trace[1709108705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6860; }","duration":"102.421283ms","start":"2026-08-26T00:26:32.019252Z","end":"2026-08-26T00:26:32.121673Z","steps":["trace[1709108705] 'agreement among raft nodes before linearized reading' (duration: 102.15928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:26:32.121693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.175ms","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-08-26T00:26:32.121825Z","caller":"traceutil/trace.go:171","msg":"trace[230756565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6860; }","duration":"102.402802ms","start":"2026-08-26T00:26:32.019397Z","end":"2026-08-26T00:26:32.1218Z","steps":["trace[230756565] 'agreement among raft nodes before linearized reading' (duration: 102.024888ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:27:28.629562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5127} {"level":"info","ts":"2026-08-26T00:27:28.723888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5127,"took":"91.522986ms","hash":2033528168,"current-db-size-bytes":52924416,"current-db-size":"53 MB","current-db-size-in-use-bytes":19349504,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-26T00:27:28.724162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2033528168,"revision":5127,"compact-revision":2586}