{"level":"warn","ts":"2026-08-22T14:31:44.027839Z","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-22T14:31:44.027931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-22T14:31:44.027997Z","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-22T14:31:44.028013Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-22T14:31:44.028036Z","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-22T14:31:44.028778Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-08-22T14:31:44.028889Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-22T14:31:44.036193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.43449ms"} {"level":"info","ts":"2026-08-22T14:31:44.048988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-08-22T14:31:44.04913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:31:44.049178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-08-22T14:31:44.049199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:31:44.049227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-08-22T14:31:44.049335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-08-22T14:31:44.057919Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:31:44.060312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:31:44.062166Z","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-22T14:31:44.068899Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:31:44.073603Z","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-22T14:31:44.073818Z","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-22T14:31:44.073834Z","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-22T14:31:44.074098Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:31:44.07543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-08-22T14:31:44.075642Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-22T14:31:44.077165Z","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-22T14:31:44.077439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-22T14:31:44.077472Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-22T14:31:44.07781Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:31:44.077852Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:31:44.349577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:31:44.349726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:31:44.349763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-08-22T14:31:44.349786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:31:44.349804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-22T14:31:44.349822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-08-22T14:31:44.349844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-22T14:31:44.353074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:31:44.35313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:44.353255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:31:44.353344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:31:44.35519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:44.355416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:44.355593Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:44.356113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-08-22T14:31:44.356589Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:31:44.35664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:31:44.357344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:31:56.348005Z","caller":"traceutil/trace.go:171","msg":"trace[972210374] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"111.412377ms","start":"2026-08-22T14:31:56.236556Z","end":"2026-08-22T14:31:56.347968Z","steps":["trace[972210374] 'process raft request' (duration: 84.85484ms)","trace[972210374] 'compare' (duration: 26.365456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:13.245666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.482813ms","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-22T14:32:13.245823Z","caller":"traceutil/trace.go:171","msg":"trace[1431837962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"830.782345ms","start":"2026-08-22T14:32:12.414997Z","end":"2026-08-22T14:32:13.245779Z","steps":["trace[1431837962] 'range keys from in-memory index tree' (duration: 830.250592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:13.245879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:12.414934Z","time spent":"830.931746ms","remote":"127.0.0.1:45358","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-22T14:32:13.245854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.697885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T14:32:13.246046Z","caller":"traceutil/trace.go:171","msg":"trace[1378647869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"830.949666ms","start":"2026-08-22T14:32:12.415067Z","end":"2026-08-22T14:32:13.246017Z","steps":["trace[1378647869] 'range keys from in-memory index tree' (duration: 830.472904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:13.246108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:12.415039Z","time spent":"831.051957ms","remote":"127.0.0.1:45358","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-22T14:32:13.246429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.532063ms","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-22T14:32:13.246474Z","caller":"traceutil/trace.go:171","msg":"trace[311315953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"864.624553ms","start":"2026-08-22T14:32:12.381835Z","end":"2026-08-22T14:32:13.24646Z","steps":["trace[311315953] 'range keys from in-memory index tree' (duration: 864.424342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:13.246512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:12.381807Z","time spent":"864.694774ms","remote":"127.0.0.1:45358","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-22T14:32:13.392996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.015526ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:32:13.393409Z","caller":"traceutil/trace.go:171","msg":"trace[1587914587] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"140.427112ms","start":"2026-08-22T14:32:13.25296Z","end":"2026-08-22T14:32:13.393387Z","steps":["trace[1587914587] 'process raft request' (duration: 140.326992ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:13.393601Z","caller":"traceutil/trace.go:171","msg":"trace[68804057] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:488; }","duration":"103.408944ms","start":"2026-08-22T14:32:13.290178Z","end":"2026-08-22T14:32:13.393586Z","steps":["trace[68804057] 'read index received' (duration: 87.906285ms)","trace[68804057] 'applied index is now lower than readState.Index' (duration: 15.501119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:13.393734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.527295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:13.393767Z","caller":"traceutil/trace.go:171","msg":"trace[73777851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"103.612335ms","start":"2026-08-22T14:32:13.290144Z","end":"2026-08-22T14:32:13.393757Z","steps":["trace[73777851] 'agreement among raft nodes before linearized reading' (duration: 103.510475ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:13.393736Z","caller":"traceutil/trace.go:171","msg":"trace[271085763] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"140.137801ms","start":"2026-08-22T14:32:13.253525Z","end":"2026-08-22T14:32:13.393663Z","steps":["trace[271085763] 'process raft request' (duration: 139.824069ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:13.393475Z","caller":"traceutil/trace.go:171","msg":"trace[882768944] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"142.191684ms","start":"2026-08-22T14:32:13.25121Z","end":"2026-08-22T14:32:13.393401Z","steps":["trace[882768944] 'process raft request' (duration: 141.922073ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:18.498423Z","caller":"traceutil/trace.go:171","msg":"trace[137797619] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"160.210458ms","start":"2026-08-22T14:32:18.338183Z","end":"2026-08-22T14:32:18.498393Z","steps":["trace[137797619] 'process raft request' (duration: 160.005007ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:24.056146Z","caller":"traceutil/trace.go:171","msg":"trace[149305396] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"294.045461ms","start":"2026-08-22T14:32:23.762018Z","end":"2026-08-22T14:32:24.056064Z","steps":["trace[149305396] 'process raft request' (duration: 284.906783ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:24.056577Z","caller":"traceutil/trace.go:171","msg":"trace[920056947] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"215.756962ms","start":"2026-08-22T14:32:23.840788Z","end":"2026-08-22T14:32:24.056545Z","steps":["trace[920056947] 'process raft request' (duration: 215.649387ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:24.056721Z","caller":"traceutil/trace.go:171","msg":"trace[1757120685] transaction","detail":"{read_only:false; number_of_response:1; response_revision:584; }","duration":"257.828631ms","start":"2026-08-22T14:32:23.798847Z","end":"2026-08-22T14:32:24.056675Z","steps":["trace[1757120685] 'process raft request' (duration: 257.218453ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:24.581057Z","caller":"traceutil/trace.go:171","msg":"trace[260233875] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"349.480222ms","start":"2026-08-22T14:32:24.231539Z","end":"2026-08-22T14:32:24.58102Z","steps":["trace[260233875] 'process raft request' (duration: 349.299343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:24.581713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:24.231513Z","time spent":"349.65343ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:32:24.592493Z","caller":"traceutil/trace.go:171","msg":"trace[1306612708] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"299.792637ms","start":"2026-08-22T14:32:24.292673Z","end":"2026-08-22T14:32:24.592465Z","steps":["trace[1306612708] 'read index received' (duration: 288.348153ms)","trace[1306612708] 'applied index is now lower than readState.Index' (duration: 11.443424ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:24.592635Z","caller":"traceutil/trace.go:171","msg":"trace[1055316981] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"358.811479ms","start":"2026-08-22T14:32:24.233816Z","end":"2026-08-22T14:32:24.592628Z","steps":["trace[1055316981] 'process raft request' (duration: 358.342747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:24.59287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.151414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:32:24.592963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:24.233707Z","time spent":"358.952546ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:32:24.592979Z","caller":"traceutil/trace.go:171","msg":"trace[705061260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"300.349072ms","start":"2026-08-22T14:32:24.292599Z","end":"2026-08-22T14:32:24.592948Z","steps":["trace[705061260] 'agreement among raft nodes before linearized reading' (duration: 300.129162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:24.593031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:24.292572Z","time spent":"300.449696ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:32:24.593785Z","caller":"traceutil/trace.go:171","msg":"trace[829352342] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"334.222274ms","start":"2026-08-22T14:32:24.25953Z","end":"2026-08-22T14:32:24.593752Z","steps":["trace[829352342] 'process raft request' (duration: 332.853001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:24.593958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:24.259493Z","time spent":"334.364969ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:32:31.034532Z","caller":"traceutil/trace.go:171","msg":"trace[492769029] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"210.889266ms","start":"2026-08-22T14:32:30.823608Z","end":"2026-08-22T14:32:31.034497Z","steps":["trace[492769029] 'process raft request' (duration: 209.300709ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:31.54655Z","caller":"traceutil/trace.go:171","msg":"trace[876387654] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"252.838289ms","start":"2026-08-22T14:32:31.293678Z","end":"2026-08-22T14:32:31.546517Z","steps":["trace[876387654] 'read index received' (duration: 252.531926ms)","trace[876387654] 'applied index is now lower than readState.Index' (duration: 305.113µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:31.546793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.08934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:31.546741Z","caller":"traceutil/trace.go:171","msg":"trace[1016448563] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"456.296301ms","start":"2026-08-22T14:32:31.090377Z","end":"2026-08-22T14:32:31.546673Z","steps":["trace[1016448563] 'process raft request' (duration: 455.918346ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:31.546844Z","caller":"traceutil/trace.go:171","msg":"trace[302292851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"253.226005ms","start":"2026-08-22T14:32:31.293605Z","end":"2026-08-22T14:32:31.546831Z","steps":["trace[302292851] 'agreement among raft nodes before linearized reading' (duration: 253.089309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:31.547042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:31.090344Z","time spent":"456.526811ms","remote":"127.0.0.1:45334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:31.547416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.972602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-555qd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-22T14:32:31.547727Z","caller":"traceutil/trace.go:171","msg":"trace[591021883] range","detail":"{range_begin:/registry/pods/kube-system/cilium-555qd; range_end:; response_count:1; response_revision:646; }","duration":"232.356227ms","start":"2026-08-22T14:32:31.315343Z","end":"2026-08-22T14:32:31.547699Z","steps":["trace[591021883] 'agreement among raft nodes before linearized reading' (duration: 231.589325ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:31.988416Z","caller":"traceutil/trace.go:171","msg":"trace[1653509704] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"434.65577ms","start":"2026-08-22T14:32:31.553725Z","end":"2026-08-22T14:32:31.988381Z","steps":["trace[1653509704] 'process raft request' (duration: 434.389789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:31.988595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:31.553702Z","time spent":"434.799787ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:32:31.988899Z","caller":"traceutil/trace.go:171","msg":"trace[211074362] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"430.620491ms","start":"2026-08-22T14:32:31.558258Z","end":"2026-08-22T14:32:31.988878Z","steps":["trace[211074362] 'process raft request' (duration: 430.082748ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:31.989051Z","caller":"traceutil/trace.go:171","msg":"trace[463927037] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"432.096873ms","start":"2026-08-22T14:32:31.55686Z","end":"2026-08-22T14:32:31.988957Z","steps":["trace[463927037] 'read index received' (duration: 23.371184ms)","trace[463927037] 'applied index is now lower than readState.Index' (duration: 408.723949ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:31.989333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.437867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-22T14:32:31.989406Z","caller":"traceutil/trace.go:171","msg":"trace[173189052] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:650; }","duration":"432.544021ms","start":"2026-08-22T14:32:31.556839Z","end":"2026-08-22T14:32:31.989383Z","steps":["trace[173189052] 'agreement among raft nodes before linearized reading' (duration: 432.352783ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:31.98938Z","caller":"traceutil/trace.go:171","msg":"trace[1564002631] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"425.680943ms","start":"2026-08-22T14:32:31.563659Z","end":"2026-08-22T14:32:31.98934Z","steps":["trace[1564002631] 'process raft request' (duration: 424.975834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:31.989458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:31.556831Z","time spent":"432.613404ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-22T14:32:31.989116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:31.55823Z","time spent":"430.83376ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:31.989605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:31.563647Z","time spent":"425.827359ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:32.793102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298364506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:32:33.601113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.456453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:32:33.601522Z","caller":"traceutil/trace.go:171","msg":"trace[2112980023] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.551970524s","start":"2026-08-22T14:32:32.049493Z","end":"2026-08-22T14:32:33.601464Z","steps":["trace[2112980023] 'process raft request' (duration: 1.551758835s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:33.601557Z","caller":"traceutil/trace.go:171","msg":"trace[1768716851] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"1.309518792s","start":"2026-08-22T14:32:32.292006Z","end":"2026-08-22T14:32:33.601525Z","steps":["trace[1768716851] 'read index received' (duration: 660.431283ms)","trace[1768716851] 'applied index is now lower than readState.Index' (duration: 649.084988ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:33.601566Z","caller":"traceutil/trace.go:171","msg":"trace[1815839123] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.551823897s","start":"2026-08-22T14:32:32.049687Z","end":"2026-08-22T14:32:33.601511Z","steps":["trace[1815839123] 'process raft request' (duration: 1.55164879s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:33.601709Z","caller":"traceutil/trace.go:171","msg":"trace[249184954] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.552280648s","start":"2026-08-22T14:32:32.049386Z","end":"2026-08-22T14:32:33.601667Z","steps":["trace[249184954] 'process raft request' (duration: 903.132026ms)","trace[249184954] 'compare' (duration: 647.839928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:33.601728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:32.049462Z","time spent":"1.552156613s","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:32:33.601812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:32.049676Z","time spent":"1.552000356s","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:33.601855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:32.049358Z","time spent":"1.552425214s","remote":"127.0.0.1:45334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:33.661428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369396854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:32:33.661468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.905504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:33.661532Z","caller":"traceutil/trace.go:171","msg":"trace[1764521112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.36956515s","start":"2026-08-22T14:32:32.291944Z","end":"2026-08-22T14:32:33.66151Z","steps":["trace[1764521112] 'agreement among raft nodes before linearized reading' (duration: 1.30969187s)","trace[1764521112] 'range keys from in-memory index tree' (duration: 59.726474ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:33.661551Z","caller":"traceutil/trace.go:171","msg":"trace[881294971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"135.042459ms","start":"2026-08-22T14:32:33.526486Z","end":"2026-08-22T14:32:33.661529Z","steps":["trace[881294971] 'agreement among raft nodes before linearized reading' (duration: 134.882182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:33.661581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:32.291909Z","time spent":"1.369661494s","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:32:33.661722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013027954s","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-22T14:32:33.661792Z","caller":"traceutil/trace.go:171","msg":"trace[207216786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"1.013142109s","start":"2026-08-22T14:32:32.648633Z","end":"2026-08-22T14:32:33.661776Z","steps":["trace[207216786] 'agreement among raft nodes before linearized reading' (duration: 1.012898129s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:33.661837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:32.64861Z","time spent":"1.013218362s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:32:49.792089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298364816,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:32:49.906136Z","caller":"traceutil/trace.go:171","msg":"trace[583840103] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"614.596298ms","start":"2026-08-22T14:32:49.291516Z","end":"2026-08-22T14:32:49.906113Z","steps":["trace[583840103] 'read index received' (duration: 614.035198ms)","trace[583840103] 'applied index is now lower than readState.Index' (duration: 559.68µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:49.906385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.899458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-msklh\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-22T14:32:49.906461Z","caller":"traceutil/trace.go:171","msg":"trace[611334325] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-msklh; range_end:; response_count:1; response_revision:807; }","duration":"478.045423ms","start":"2026-08-22T14:32:49.428405Z","end":"2026-08-22T14:32:49.90645Z","steps":["trace[611334325] 'agreement among raft nodes before linearized reading' (duration: 477.848046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:49.906498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:49.42838Z","time spent":"478.109595ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-msklh\" "} {"level":"warn","ts":"2026-08-22T14:32:49.906506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.401312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:32:49.906568Z","caller":"traceutil/trace.go:171","msg":"trace[1604069873] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:807; }","duration":"307.533707ms","start":"2026-08-22T14:32:49.599018Z","end":"2026-08-22T14:32:49.906552Z","steps":["trace[1604069873] 'agreement among raft nodes before linearized reading' (duration: 307.399962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:49.906615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:49.598972Z","time spent":"307.63333ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:32:49.906589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.228086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T14:32:49.906682Z","caller":"traceutil/trace.go:171","msg":"trace[83086396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"106.372081ms","start":"2026-08-22T14:32:49.800293Z","end":"2026-08-22T14:32:49.906665Z","steps":["trace[83086396] 'agreement among raft nodes before linearized reading' (duration: 106.067531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:49.906573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.806624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:49.906859Z","caller":"traceutil/trace.go:171","msg":"trace[342750283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"615.365783ms","start":"2026-08-22T14:32:49.291438Z","end":"2026-08-22T14:32:49.906804Z","steps":["trace[342750283] 'agreement among raft nodes before linearized reading' (duration: 614.778003ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:49.906874Z","caller":"traceutil/trace.go:171","msg":"trace[522477361] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"649.449389ms","start":"2026-08-22T14:32:49.256741Z","end":"2026-08-22T14:32:49.90619Z","steps":["trace[522477361] 'process raft request' (duration: 648.841308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:49.907405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:49.256702Z","time spent":"650.460224ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:32:49.906926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:32:49.291409Z","time spent":"615.504638ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:32:50.041989Z","caller":"traceutil/trace.go:171","msg":"trace[363810981] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"129.239039ms","start":"2026-08-22T14:32:49.912705Z","end":"2026-08-22T14:32:50.041944Z","steps":["trace[363810981] 'process raft request' (duration: 126.783186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:50.043288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.192186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:50.043954Z","caller":"traceutil/trace.go:171","msg":"trace[1568032879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"132.74733ms","start":"2026-08-22T14:32:49.911176Z","end":"2026-08-22T14:32:50.043923Z","steps":["trace[1568032879] 'range keys from in-memory index tree' (duration: 130.491712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:50.048049Z","caller":"traceutil/trace.go:171","msg":"trace[1665659728] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"135.128479ms","start":"2026-08-22T14:32:49.912893Z","end":"2026-08-22T14:32:50.048022Z","steps":["trace[1665659728] 'process raft request' (duration: 134.927372ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:50.048213Z","caller":"traceutil/trace.go:171","msg":"trace[1785566824] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"131.969331ms","start":"2026-08-22T14:32:49.916225Z","end":"2026-08-22T14:32:50.048194Z","steps":["trace[1785566824] 'process raft request' (duration: 131.724812ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:52.02351Z","caller":"traceutil/trace.go:171","msg":"trace[1424896539] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"105.02646ms","start":"2026-08-22T14:32:51.918447Z","end":"2026-08-22T14:32:52.023474Z","steps":["trace[1424896539] 'process raft request' (duration: 104.835173ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:01.161609Z","caller":"traceutil/trace.go:171","msg":"trace[1673490726] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"108.170296ms","start":"2026-08-22T14:33:01.053404Z","end":"2026-08-22T14:33:01.161575Z","steps":["trace[1673490726] 'process raft request' (duration: 107.941539ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:02.293794Z","caller":"traceutil/trace.go:171","msg":"trace[582020702] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"213.560223ms","start":"2026-08-22T14:33:02.080208Z","end":"2026-08-22T14:33:02.293769Z","steps":["trace[582020702] 'read index received' (duration: 213.362016ms)","trace[582020702] 'applied index is now lower than readState.Index' (duration: 197.177µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:02.293943Z","caller":"traceutil/trace.go:171","msg":"trace[1759749084] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"708.017532ms","start":"2026-08-22T14:33:01.585912Z","end":"2026-08-22T14:33:02.29393Z","steps":["trace[1759749084] 'process raft request' (duration: 707.733394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:02.29405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:01.585886Z","time spent":"708.086695ms","remote":"127.0.0.1:45548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:33:02.294457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.181711ms","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-22T14:33:02.294568Z","caller":"traceutil/trace.go:171","msg":"trace[2082321785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"167.349586ms","start":"2026-08-22T14:33:02.1272Z","end":"2026-08-22T14:33:02.294549Z","steps":["trace[2082321785] 'agreement among raft nodes before linearized reading' (duration: 167.013846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:02.294578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.365267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T14:33:02.294634Z","caller":"traceutil/trace.go:171","msg":"trace[1565169696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"214.47093ms","start":"2026-08-22T14:33:02.080151Z","end":"2026-08-22T14:33:02.294622Z","steps":["trace[1565169696] 'agreement among raft nodes before linearized reading' (duration: 214.273574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:02.29497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.797301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T14:33:02.295083Z","caller":"traceutil/trace.go:171","msg":"trace[1225202653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"111.09248ms","start":"2026-08-22T14:33:02.183969Z","end":"2026-08-22T14:33:02.295061Z","steps":["trace[1225202653] 'agreement among raft nodes before linearized reading' (duration: 110.519613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:03.048146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.818579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-22T14:33:03.048255Z","caller":"traceutil/trace.go:171","msg":"trace[48154516] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:963; }","duration":"443.013445ms","start":"2026-08-22T14:33:02.605217Z","end":"2026-08-22T14:33:03.04823Z","steps":["trace[48154516] 'range keys from in-memory index tree' (duration: 442.555102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:03.048315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:02.605191Z","time spent":"443.108347ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-08-22T14:33:03.048407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.725995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:33:03.048638Z","caller":"traceutil/trace.go:171","msg":"trace[67496899] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"360.140327ms","start":"2026-08-22T14:33:02.688462Z","end":"2026-08-22T14:33:03.048603Z","steps":["trace[67496899] 'process raft request' (duration: 149.071572ms)","trace[67496899] 'compare' (duration: 210.317203ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:03.048822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:02.688426Z","time spent":"360.283241ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:23.019986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298365638,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:33:23.094519Z","caller":"traceutil/trace.go:171","msg":"trace[647842140] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"593.041739ms","start":"2026-08-22T14:33:22.501438Z","end":"2026-08-22T14:33:23.094479Z","steps":["trace[647842140] 'process raft request' (duration: 592.828773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.094758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:22.501404Z","time spent":"593.221573ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:33:23.121665Z","caller":"traceutil/trace.go:171","msg":"trace[18685389] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1255; }","duration":"602.365261ms","start":"2026-08-22T14:33:22.519274Z","end":"2026-08-22T14:33:23.121639Z","steps":["trace[18685389] 'read index received' (duration: 574.979716ms)","trace[18685389] 'applied index is now lower than readState.Index' (duration: 27.383935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:23.121913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.608768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-22T14:33:23.121957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.386199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T14:33:23.122024Z","caller":"traceutil/trace.go:171","msg":"trace[1337067365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"602.746031ms","start":"2026-08-22T14:33:22.519258Z","end":"2026-08-22T14:33:23.122004Z","steps":["trace[1337067365] 'agreement among raft nodes before linearized reading' (duration: 602.489723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.122066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:22.519251Z","time spent":"602.804343ms","remote":"127.0.0.1:45358","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-22T14:33:23.122047Z","caller":"traceutil/trace.go:171","msg":"trace[1917945765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"597.522692ms","start":"2026-08-22T14:33:22.524509Z","end":"2026-08-22T14:33:23.122031Z","steps":["trace[1917945765] 'agreement among raft nodes before linearized reading' (duration: 597.285025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.12214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:22.524477Z","time spent":"597.648035ms","remote":"127.0.0.1:45358","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-22T14:33:23.12249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.941644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-22T14:33:23.122529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.645616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T14:33:23.122615Z","caller":"traceutil/trace.go:171","msg":"trace[2066787172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"596.050146ms","start":"2026-08-22T14:33:22.526503Z","end":"2026-08-22T14:33:23.122553Z","steps":["trace[2066787172] 'agreement among raft nodes before linearized reading' (duration: 595.80551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.122593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.688119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-08-22T14:33:23.122634Z","caller":"traceutil/trace.go:171","msg":"trace[2068634390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"597.766368ms","start":"2026-08-22T14:33:22.524851Z","end":"2026-08-22T14:33:23.122617Z","steps":["trace[2068634390] 'agreement among raft nodes before linearized reading' (duration: 597.480411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.122671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:22.526493Z","time spent":"596.166009ms","remote":"127.0.0.1:45358","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-22T14:33:23.122679Z","caller":"traceutil/trace.go:171","msg":"trace[2005203680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1220; }","duration":"117.869503ms","start":"2026-08-22T14:33:23.004792Z","end":"2026-08-22T14:33:23.122662Z","steps":["trace[2005203680] 'agreement among raft nodes before linearized reading' (duration: 117.447233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:23.122691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:22.524842Z","time spent":"597.83417ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T14:33:41.506364Z","caller":"traceutil/trace.go:171","msg":"trace[787097368] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1406; }","duration":"210.590387ms","start":"2026-08-22T14:33:41.295743Z","end":"2026-08-22T14:33:41.506334Z","steps":["trace[787097368] 'read index received' (duration: 1.763077ms)","trace[787097368] 'applied index is now lower than readState.Index' (duration: 208.82599ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:41.506431Z","caller":"traceutil/trace.go:171","msg":"trace[674136777] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"214.12252ms","start":"2026-08-22T14:33:41.292277Z","end":"2026-08-22T14:33:41.5064Z","steps":["trace[674136777] 'process raft request' (duration: 213.776213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.506921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.921543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-22T14:33:41.507001Z","caller":"traceutil/trace.go:171","msg":"trace[775281951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"211.280071ms","start":"2026-08-22T14:33:41.295698Z","end":"2026-08-22T14:33:41.506978Z","steps":["trace[775281951] 'agreement among raft nodes before linearized reading' (duration: 210.805951ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:41.50657Z","caller":"traceutil/trace.go:171","msg":"trace[1312465512] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"214.170661ms","start":"2026-08-22T14:33:41.29228Z","end":"2026-08-22T14:33:41.506451Z","steps":["trace[1312465512] 'process raft request' (duration: 213.956927ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:47.49989Z","caller":"traceutil/trace.go:171","msg":"trace[233300513] linearizableReadLoop","detail":"{readStateIndex:1530; appliedIndex:1529; }","duration":"130.508735ms","start":"2026-08-22T14:33:47.369347Z","end":"2026-08-22T14:33:47.499856Z","steps":["trace[233300513] 'read index received' (duration: 62.691996ms)","trace[233300513] 'applied index is now lower than readState.Index' (duration: 67.815379ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:47.499966Z","caller":"traceutil/trace.go:171","msg":"trace[1329979943] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"162.97489ms","start":"2026-08-22T14:33:47.336945Z","end":"2026-08-22T14:33:47.49992Z","steps":["trace[1329979943] 'process raft request' (duration: 95.138611ms)","trace[1329979943] 'compare' (duration: 67.601575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:47.500107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.724509ms","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-22T14:33:47.500206Z","caller":"traceutil/trace.go:171","msg":"trace[81541644] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1484; }","duration":"130.907212ms","start":"2026-08-22T14:33:47.369277Z","end":"2026-08-22T14:33:47.500185Z","steps":["trace[81541644] 'agreement among raft nodes before linearized reading' (duration: 130.698528ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:47.673021Z","caller":"traceutil/trace.go:171","msg":"trace[1876426211] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1530; }","duration":"138.210868ms","start":"2026-08-22T14:33:47.534773Z","end":"2026-08-22T14:33:47.672984Z","steps":["trace[1876426211] 'read index received' (duration: 135.660158ms)","trace[1876426211] 'applied index is now lower than readState.Index' (duration: 2.54891ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:47.67319Z","caller":"traceutil/trace.go:171","msg":"trace[640347908] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"142.85465ms","start":"2026-08-22T14:33:47.530292Z","end":"2026-08-22T14:33:47.673147Z","steps":["trace[640347908] 'process raft request' (duration: 140.187897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:47.673376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.569946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T14:33:47.673438Z","caller":"traceutil/trace.go:171","msg":"trace[409034438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1485; }","duration":"138.692649ms","start":"2026-08-22T14:33:47.534717Z","end":"2026-08-22T14:33:47.67341Z","steps":["trace[409034438] 'agreement among raft nodes before linearized reading' (duration: 138.456484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:47.673536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.957426ms","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-22T14:33:47.673635Z","caller":"traceutil/trace.go:171","msg":"trace[381064785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1485; }","duration":"121.111778ms","start":"2026-08-22T14:33:47.552497Z","end":"2026-08-22T14:33:47.673608Z","steps":["trace[381064785] 'agreement among raft nodes before linearized reading' (duration: 120.868014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.115346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.535677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:34:15.11545Z","caller":"traceutil/trace.go:171","msg":"trace[1509642666] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"1.178160104s","start":"2026-08-22T14:34:13.937273Z","end":"2026-08-22T14:34:15.115433Z","steps":["trace[1509642666] 'read index received' (duration: 32.236227ms)","trace[1509642666] 'applied index is now lower than readState.Index' (duration: 1.145922477s)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:15.115545Z","caller":"traceutil/trace.go:171","msg":"trace[713964937] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"1.222383591s","start":"2026-08-22T14:34:13.893123Z","end":"2026-08-22T14:34:15.115507Z","steps":["trace[713964937] 'process raft request' (duration: 334.553229ms)","trace[713964937] 'compare' (duration: 887.264402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:34:15.115641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178368528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-22T14:34:15.115668Z","caller":"traceutil/trace.go:171","msg":"trace[1908203376] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b; range_end:; response_count:1; response_revision:1896; }","duration":"1.178446198s","start":"2026-08-22T14:34:13.937211Z","end":"2026-08-22T14:34:15.115657Z","steps":["trace[1908203376] 'agreement among raft nodes before linearized reading' (duration: 1.178304616s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.115695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:13.937187Z","time spent":"1.17850039s","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b\" "} {"level":"warn","ts":"2026-08-22T14:34:15.115718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:13.893111Z","time spent":"1.222514283s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:34:15.115908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14009788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T14:34:15.115932Z","caller":"traceutil/trace.go:171","msg":"trace[2033350312] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1896; }","duration":"1.140165532s","start":"2026-08-22T14:34:13.975759Z","end":"2026-08-22T14:34:15.115924Z","steps":["trace[2033350312] 'agreement among raft nodes before linearized reading' (duration: 1.14009579s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.115954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:13.975734Z","time spent":"1.140215672s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T14:34:15.116066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.01598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:34:15.116069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.837806ms","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-22T14:34:15.116084Z","caller":"traceutil/trace.go:171","msg":"trace[1926542706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"823.090841ms","start":"2026-08-22T14:34:14.292987Z","end":"2026-08-22T14:34:15.116078Z","steps":["trace[1926542706] 'agreement among raft nodes before linearized reading' (duration: 823.060131ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:15.116098Z","caller":"traceutil/trace.go:171","msg":"trace[105155576] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1896; }","duration":"466.921857ms","start":"2026-08-22T14:34:14.649168Z","end":"2026-08-22T14:34:15.11609Z","steps":["trace[105155576] 'agreement among raft nodes before linearized reading' (duration: 466.868826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.116105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:14.292961Z","time spent":"823.140301ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:34:15.116121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:14.649128Z","time spent":"466.987498ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:34:15.116263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.018894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-22T14:34:15.116287Z","caller":"traceutil/trace.go:171","msg":"trace[1041368523] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1896; }","duration":"993.100015ms","start":"2026-08-22T14:34:14.123177Z","end":"2026-08-22T14:34:15.116277Z","steps":["trace[1041368523] 'agreement among raft nodes before linearized reading' (duration: 993.016974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.116306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:14.123142Z","time spent":"993.159816ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:34:15.116371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.728573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-22T14:34:15.116392Z","caller":"traceutil/trace.go:171","msg":"trace[2143506749] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1896; }","duration":"272.795373ms","start":"2026-08-22T14:34:14.843591Z","end":"2026-08-22T14:34:15.116386Z","steps":["trace[2143506749] 'agreement among raft nodes before linearized reading' (duration: 272.690711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.116626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01100405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-22T14:34:15.116719Z","caller":"traceutil/trace.go:171","msg":"trace[1090410911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b; range_end:; response_count:1; response_revision:1896; }","duration":"1.011163513s","start":"2026-08-22T14:34:14.105531Z","end":"2026-08-22T14:34:15.116694Z","steps":["trace[1090410911] 'agreement among raft nodes before linearized reading' (duration: 1.010876878s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:15.116764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:14.105459Z","time spent":"1.011296035s","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49461999-9826-4518-9a7e-36da9be8007b\" "} {"level":"info","ts":"2026-08-22T14:34:15.352507Z","caller":"traceutil/trace.go:171","msg":"trace[1198479618] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"228.927547ms","start":"2026-08-22T14:34:15.123542Z","end":"2026-08-22T14:34:15.35247Z","steps":["trace[1198479618] 'process raft request' (duration: 223.482557ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:15.353264Z","caller":"traceutil/trace.go:171","msg":"trace[2008336782] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"229.149139ms","start":"2026-08-22T14:34:15.124094Z","end":"2026-08-22T14:34:15.353243Z","steps":["trace[2008336782] 'process raft request' (duration: 228.281235ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:15.5818Z","caller":"traceutil/trace.go:171","msg":"trace[532682363] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"144.804583ms","start":"2026-08-22T14:34:15.436959Z","end":"2026-08-22T14:34:15.581764Z","steps":["trace[532682363] 'process raft request' (duration: 107.54751ms)","trace[532682363] 'compare' (duration: 37.000189ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:15.582217Z","caller":"traceutil/trace.go:171","msg":"trace[432751703] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"143.034513ms","start":"2026-08-22T14:34:15.439137Z","end":"2026-08-22T14:34:15.582171Z","steps":["trace[432751703] 'process raft request' (duration: 142.548386ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:16.171241Z","caller":"traceutil/trace.go:171","msg":"trace[44806334] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"446.003971ms","start":"2026-08-22T14:34:15.725215Z","end":"2026-08-22T14:34:16.171219Z","steps":["trace[44806334] 'process raft request' (duration: 445.843789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:16.171399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:15.725201Z","time spent":"446.110123ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:16.209504Z","caller":"traceutil/trace.go:171","msg":"trace[1980456191] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"479.347436ms","start":"2026-08-22T14:34:15.730123Z","end":"2026-08-22T14:34:16.209471Z","steps":["trace[1980456191] 'process raft request' (duration: 479.157473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:16.209835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:15.730091Z","time spent":"479.505389ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:16.20987Z","caller":"traceutil/trace.go:171","msg":"trace[390043299] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1975; }","duration":"212.471806ms","start":"2026-08-22T14:34:15.997368Z","end":"2026-08-22T14:34:16.20984Z","steps":["trace[390043299] 'read index received' (duration: 173.727942ms)","trace[390043299] 'applied index is now lower than readState.Index' (duration: 38.742194ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:16.210017Z","caller":"traceutil/trace.go:171","msg":"trace[1308605092] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"340.451191ms","start":"2026-08-22T14:34:15.869552Z","end":"2026-08-22T14:34:16.210003Z","steps":["trace[1308605092] 'process raft request' (duration: 339.869341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:16.210165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:15.869523Z","time spent":"340.538263ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:16.210328Z","caller":"traceutil/trace.go:171","msg":"trace[575497381] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"294.275382ms","start":"2026-08-22T14:34:15.916038Z","end":"2026-08-22T14:34:16.210313Z","steps":["trace[575497381] 'process raft request' (duration: 293.681572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:16.2106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.851621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-22T14:34:16.210668Z","caller":"traceutil/trace.go:171","msg":"trace[167180789] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1906; }","duration":"130.954673ms","start":"2026-08-22T14:34:16.079698Z","end":"2026-08-22T14:34:16.210652Z","steps":["trace[167180789] 'agreement among raft nodes before linearized reading' (duration: 130.686248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:16.210851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.491104ms","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-22T14:34:16.210906Z","caller":"traceutil/trace.go:171","msg":"trace[1703344322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1906; }","duration":"213.611306ms","start":"2026-08-22T14:34:15.997283Z","end":"2026-08-22T14:34:16.210894Z","steps":["trace[1703344322] 'agreement among raft nodes before linearized reading' (duration: 213.487434ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:16.319089Z","caller":"traceutil/trace.go:171","msg":"trace[1437756194] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"102.955814ms","start":"2026-08-22T14:34:16.216095Z","end":"2026-08-22T14:34:16.319051Z","steps":["trace[1437756194] 'process raft request' (duration: 38.697554ms)","trace[1437756194] 'compare' (duration: 64.050447ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:16.408869Z","caller":"traceutil/trace.go:171","msg":"trace[1949692045] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"115.413477ms","start":"2026-08-22T14:34:16.293391Z","end":"2026-08-22T14:34:16.408805Z","steps":["trace[1949692045] 'read index received' (duration: 115.284175ms)","trace[1949692045] 'applied index is now lower than readState.Index' (duration: 127.632µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:34:16.409023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.6137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:34:16.40906Z","caller":"traceutil/trace.go:171","msg":"trace[4643545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1908; }","duration":"115.675831ms","start":"2026-08-22T14:34:16.29337Z","end":"2026-08-22T14:34:16.409046Z","steps":["trace[4643545] 'agreement among raft nodes before linearized reading' (duration: 115.586269ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:16.409075Z","caller":"traceutil/trace.go:171","msg":"trace[125568140] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"192.706101ms","start":"2026-08-22T14:34:16.216338Z","end":"2026-08-22T14:34:16.409044Z","steps":["trace[125568140] 'process raft request' (duration: 192.271434ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:24.403462Z","caller":"traceutil/trace.go:171","msg":"trace[1335029359] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"152.620735ms","start":"2026-08-22T14:34:24.250816Z","end":"2026-08-22T14:34:24.403437Z","steps":["trace[1335029359] 'process raft request' (duration: 152.411252ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:24.694784Z","caller":"traceutil/trace.go:171","msg":"trace[1040487142] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2063; }","duration":"423.928998ms","start":"2026-08-22T14:34:24.270829Z","end":"2026-08-22T14:34:24.694758Z","steps":["trace[1040487142] 'read index received' (duration: 131.251063ms)","trace[1040487142] 'applied index is now lower than readState.Index' (duration: 292.676765ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:24.694864Z","caller":"traceutil/trace.go:171","msg":"trace[45650504] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"443.855609ms","start":"2026-08-22T14:34:24.250942Z","end":"2026-08-22T14:34:24.694798Z","steps":["trace[45650504] 'process raft request' (duration: 443.401582ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:24.694932Z","caller":"traceutil/trace.go:171","msg":"trace[1942813467] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"440.636928ms","start":"2026-08-22T14:34:24.254254Z","end":"2026-08-22T14:34:24.694891Z","steps":["trace[1942813467] 'process raft request' (duration: 440.454575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:24.695062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.215192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T14:34:24.695111Z","caller":"traceutil/trace.go:171","msg":"trace[1874540127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1990; }","duration":"424.323603ms","start":"2026-08-22T14:34:24.270775Z","end":"2026-08-22T14:34:24.695098Z","steps":["trace[1874540127] 'agreement among raft nodes before linearized reading' (duration: 424.138541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:24.695105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:24.25091Z","time spent":"444.055002ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:34:24.695151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:24.27076Z","time spent":"424.382025ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T14:34:24.695182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:24.25422Z","time spent":"440.817751ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:34:24.695701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.814119ms","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-22T14:34:24.695782Z","caller":"traceutil/trace.go:171","msg":"trace[167807351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1990; }","duration":"342.957162ms","start":"2026-08-22T14:34:24.352806Z","end":"2026-08-22T14:34:24.695763Z","steps":["trace[167807351] 'agreement among raft nodes before linearized reading' (duration: 342.731448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:24.695829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:24.352786Z","time spent":"343.031243ms","remote":"127.0.0.1:45358","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-22T14:34:24.696254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.515589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:34:24.696307Z","caller":"traceutil/trace.go:171","msg":"trace[1012607369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1990; }","duration":"403.59259ms","start":"2026-08-22T14:34:24.292698Z","end":"2026-08-22T14:34:24.696291Z","steps":["trace[1012607369] 'agreement among raft nodes before linearized reading' (duration: 402.738816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:24.696346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:24.292687Z","time spent":"403.647191ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:34:50.53501Z","caller":"traceutil/trace.go:171","msg":"trace[468186471] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"103.450371ms","start":"2026-08-22T14:34:50.431535Z","end":"2026-08-22T14:34:50.534986Z","steps":["trace[468186471] 'process raft request' (duration: 103.291728ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:51.349012Z","caller":"traceutil/trace.go:171","msg":"trace[2065870583] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"346.943929ms","start":"2026-08-22T14:34:51.002019Z","end":"2026-08-22T14:34:51.348963Z","steps":["trace[2065870583] 'process raft request' (duration: 311.027924ms)","trace[2065870583] 'compare' (duration: 35.378218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:34:51.34923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:51.001989Z","time spent":"347.118252ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:51.349259Z","caller":"traceutil/trace.go:171","msg":"trace[987553261] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"346.718577ms","start":"2026-08-22T14:34:51.002502Z","end":"2026-08-22T14:34:51.34922Z","steps":["trace[987553261] 'process raft request' (duration: 346.363972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:51.349463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:51.002494Z","time spent":"346.847038ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:34:52.277644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298367601,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:34:52.664264Z","caller":"traceutil/trace.go:171","msg":"trace[2020304532] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"887.264925ms","start":"2026-08-22T14:34:51.776975Z","end":"2026-08-22T14:34:52.66424Z","steps":["trace[2020304532] 'read index received' (duration: 887.109153ms)","trace[2020304532] 'applied index is now lower than readState.Index' (duration: 154.272µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:52.664443Z","caller":"traceutil/trace.go:171","msg":"trace[1485023038] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"894.441754ms","start":"2026-08-22T14:34:51.769883Z","end":"2026-08-22T14:34:52.664324Z","steps":["trace[1485023038] 'process raft request' (duration: 894.13296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:52.66453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.527229ms","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-22T14:34:52.664575Z","caller":"traceutil/trace.go:171","msg":"trace[1455879284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"887.657882ms","start":"2026-08-22T14:34:51.776906Z","end":"2026-08-22T14:34:52.664564Z","steps":["trace[1455879284] 'agreement among raft nodes before linearized reading' (duration: 887.464778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:52.664611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:51.776884Z","time spent":"887.718542ms","remote":"127.0.0.1:45358","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-22T14:34:52.66474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:51.769861Z","time spent":"894.686218ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:52.685317Z","caller":"traceutil/trace.go:171","msg":"trace[107072861] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"125.944948ms","start":"2026-08-22T14:34:52.55935Z","end":"2026-08-22T14:34:52.685295Z","steps":["trace[107072861] 'process raft request' (duration: 125.623684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:52.685304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.555215ms","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-22T14:34:52.685397Z","caller":"traceutil/trace.go:171","msg":"trace[966122089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"878.702998ms","start":"2026-08-22T14:34:51.806673Z","end":"2026-08-22T14:34:52.685376Z","steps":["trace[966122089] 'agreement among raft nodes before linearized reading' (duration: 878.452584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:52.685443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:51.806653Z","time spent":"878.781138ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:34:52.68541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.329006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T14:34:52.686557Z","caller":"traceutil/trace.go:171","msg":"trace[603088579] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"684.527371ms","start":"2026-08-22T14:34:52.002011Z","end":"2026-08-22T14:34:52.686538Z","steps":["trace[603088579] 'agreement among raft nodes before linearized reading' (duration: 683.299745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:52.686678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:52.001993Z","time spent":"684.673414ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T14:34:53.254582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.821205ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-22T14:34:53.254898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:52.688902Z","time spent":"565.989001ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T14:34:53.255724Z","caller":"traceutil/trace.go:171","msg":"trace[1007745206] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2237; }","duration":"183.528059ms","start":"2026-08-22T14:34:53.072172Z","end":"2026-08-22T14:34:53.2557Z","steps":["trace[1007745206] 'read index received' (duration: 178.296677ms)","trace[1007745206] 'applied index is now lower than readState.Index' (duration: 5.229902ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:34:53.255754Z","caller":"traceutil/trace.go:171","msg":"trace[720719401] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"565.833979ms","start":"2026-08-22T14:34:52.689896Z","end":"2026-08-22T14:34:53.25573Z","steps":["trace[720719401] 'process raft request' (duration: 564.899406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:53.255887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:34:52.689872Z","time spent":"565.92782ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:34:53.256004Z","caller":"traceutil/trace.go:171","msg":"trace[366792465] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"255.36218ms","start":"2026-08-22T14:34:53.000501Z","end":"2026-08-22T14:34:53.255863Z","steps":["trace[366792465] 'process raft request' (duration: 255.126817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:53.256333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.123057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:34:53.256439Z","caller":"traceutil/trace.go:171","msg":"trace[1008778555] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2158; }","duration":"184.38312ms","start":"2026-08-22T14:34:53.072036Z","end":"2026-08-22T14:34:53.256419Z","steps":["trace[1008778555] 'agreement among raft nodes before linearized reading' (duration: 184.076345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:54.407951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.17608ms","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-22T14:35:54.40806Z","caller":"traceutil/trace.go:171","msg":"trace[1320772865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2593; }","duration":"171.353922ms","start":"2026-08-22T14:35:54.236687Z","end":"2026-08-22T14:35:54.40804Z","steps":["trace[1320772865] 'count revisions from in-memory index tree' (duration: 171.083259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:54.408104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.04607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:35:54.408195Z","caller":"traceutil/trace.go:171","msg":"trace[654268872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2593; }","duration":"115.510706ms","start":"2026-08-22T14:35:54.292665Z","end":"2026-08-22T14:35:54.408175Z","steps":["trace[654268872] 'range keys from in-memory index tree' (duration: 114.805008ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:27.50053Z","caller":"traceutil/trace.go:171","msg":"trace[654474425] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"454.383236ms","start":"2026-08-22T14:36:27.046115Z","end":"2026-08-22T14:36:27.500498Z","steps":["trace[654474425] 'process raft request' (duration: 454.209135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:27.501418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:27.04608Z","time spent":"455.233586ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:27.737432Z","caller":"traceutil/trace.go:171","msg":"trace[1116173234] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2874; }","duration":"641.797515ms","start":"2026-08-22T14:36:27.095599Z","end":"2026-08-22T14:36:27.737397Z","steps":["trace[1116173234] 'read index received' (duration: 404.806308ms)","trace[1116173234] 'applied index is now lower than readState.Index' (duration: 236.990177ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:27.737489Z","caller":"traceutil/trace.go:171","msg":"trace[2088237041] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"662.810838ms","start":"2026-08-22T14:36:27.074644Z","end":"2026-08-22T14:36:27.737455Z","steps":["trace[2088237041] 'process raft request' (duration: 642.22374ms)","trace[2088237041] 'compare' (duration: 20.389535ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:27.737665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:27.074607Z","time spent":"662.952599ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:27.737815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.196029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ce275969cf729b\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-22T14:36:27.737869Z","caller":"traceutil/trace.go:171","msg":"trace[1006606120] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ce275969cf729b; range_end:; response_count:1; response_revision:2773; }","duration":"642.322261ms","start":"2026-08-22T14:36:27.09553Z","end":"2026-08-22T14:36:27.737852Z","steps":["trace[1006606120] 'agreement among raft nodes before linearized reading' (duration: 642.033518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:27.737918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:27.0955Z","time spent":"642.407991ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ce275969cf729b\" "} {"level":"warn","ts":"2026-08-22T14:36:27.744412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.241445ms","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-22T14:36:27.744552Z","caller":"traceutil/trace.go:171","msg":"trace[734228434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2774; }","duration":"551.409897ms","start":"2026-08-22T14:36:27.19308Z","end":"2026-08-22T14:36:27.74449Z","steps":["trace[734228434] 'agreement among raft nodes before linearized reading' (duration: 551.118364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:27.744639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:27.193054Z","time spent":"551.540318ms","remote":"127.0.0.1:45358","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-22T14:36:27.744638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.078615ms","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-22T14:36:27.744771Z","caller":"traceutil/trace.go:171","msg":"trace[64134640] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2774; }","duration":"244.252457ms","start":"2026-08-22T14:36:27.500507Z","end":"2026-08-22T14:36:27.744759Z","steps":["trace[64134640] 'agreement among raft nodes before linearized reading' (duration: 244.071315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:27.744859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.163582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:27.74503Z","caller":"traceutil/trace.go:171","msg":"trace[1126073160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2774; }","duration":"452.464036ms","start":"2026-08-22T14:36:27.292541Z","end":"2026-08-22T14:36:27.745005Z","steps":["trace[1126073160] 'agreement among raft nodes before linearized reading' (duration: 452.096151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:27.74512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:27.292514Z","time spent":"452.555006ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:37:50.792729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:51.293267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:51.793635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:52.292667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000573515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-22T14:37:52.292841Z","caller":"traceutil/trace.go:171","msg":"trace[1434404817] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000739689s","start":"2026-08-22T14:37:50.291999Z","end":"2026-08-22T14:37:52.292739Z","steps":["trace[1434404817] 'agreement among raft nodes before linearized reading' (duration: 2.000619796s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:52.292953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.291978Z","time spent":"2.000914833s","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/22 14:37:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-22T14:37:52.294832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:52.796035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:53.296886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:53.797591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:37:54.013484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.834154704s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:37:54.014075Z","caller":"traceutil/trace.go:171","msg":"trace[1689098522] transaction","detail":"{read_only:false; response_revision:3210; number_of_response:1; }","duration":"3.834821973s","start":"2026-08-22T14:37:50.179223Z","end":"2026-08-22T14:37:54.014045Z","steps":["trace[1689098522] 'process raft request' (duration: 3.834510775s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.014329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.179204Z","time spent":"3.834956236s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:37:54.21561Z","caller":"traceutil/trace.go:171","msg":"trace[1512204207] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"2.12510526s","start":"2026-08-22T14:37:52.090474Z","end":"2026-08-22T14:37:54.215579Z","steps":["trace[1512204207] 'process raft request' (duration: 2.125024648s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:54.215653Z","caller":"traceutil/trace.go:171","msg":"trace[1643053932] transaction","detail":"{read_only:false; response_revision:3211; number_of_response:1; }","duration":"4.034025249s","start":"2026-08-22T14:37:50.181606Z","end":"2026-08-22T14:37:54.215631Z","steps":["trace[1643053932] 'process raft request' (duration: 4.033675189s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.215801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.090444Z","time spent":"2.125259163s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:37:54.215805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.181592Z","time spent":"4.034115301s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:37:54.215826Z","caller":"traceutil/trace.go:171","msg":"trace[1018413198] transaction","detail":"{read_only:false; response_revision:3212; number_of_response:1; }","duration":"2.137584734s","start":"2026-08-22T14:37:52.0782Z","end":"2026-08-22T14:37:54.215785Z","steps":["trace[1018413198] 'process raft request' (duration: 2.137242495s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.216127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.078168Z","time spent":"2.137849241s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:37:54.216285Z","caller":"traceutil/trace.go:171","msg":"trace[1422729454] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"2.122189993s","start":"2026-08-22T14:37:52.094044Z","end":"2026-08-22T14:37:54.216234Z","steps":["trace[1422729454] 'process raft request' (duration: 2.121495916s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.216548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.094011Z","time spent":"2.122385219s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:37:54.293272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000200201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-22T14:37:54.293357Z","caller":"traceutil/trace.go:171","msg":"trace[345880921] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000351034s","start":"2026-08-22T14:37:52.292987Z","end":"2026-08-22T14:37:54.293338Z","steps":["trace[345880921] 'agreement among raft nodes before linearized reading' (duration: 2.000244692s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.293451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.292965Z","time spent":"2.000473618s","remote":"127.0.0.1:45094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/22 14:37:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-22T14:37:54.352477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.374254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:37:54.352982Z","caller":"traceutil/trace.go:171","msg":"trace[497465336] linearizableReadLoop","detail":"{readStateIndex:3336; appliedIndex:3330; }","duration":"4.060890159s","start":"2026-08-22T14:37:50.29207Z","end":"2026-08-22T14:37:54.35296Z","steps":["trace[497465336] 'read index received' (duration: 3.721656873s)","trace[497465336] 'applied index is now lower than readState.Index' (duration: 339.231826ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:54.353102Z","caller":"traceutil/trace.go:171","msg":"trace[149584163] transaction","detail":"{read_only:false; response_revision:3215; number_of_response:1; }","duration":"387.377345ms","start":"2026-08-22T14:37:53.965693Z","end":"2026-08-22T14:37:54.35307Z","steps":["trace[149584163] 'process raft request' (duration: 386.935512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.353257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:53.965672Z","time spent":"387.499688ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:37:54.468533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.594541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:37:54.468688Z","caller":"traceutil/trace.go:171","msg":"trace[672916270] linearizableReadLoop","detail":"{readStateIndex:3338; appliedIndex:3337; }","duration":"115.645688ms","start":"2026-08-22T14:37:54.353026Z","end":"2026-08-22T14:37:54.468672Z","steps":["trace[672916270] 'read index received' (duration: 7.839331ms)","trace[672916270] 'applied index is now lower than readState.Index' (duration: 107.803537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:54.468915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.095558192s","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-22T14:37:54.468949Z","caller":"traceutil/trace.go:171","msg":"trace[1426620810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3216; }","duration":"4.095638654s","start":"2026-08-22T14:37:50.373301Z","end":"2026-08-22T14:37:54.468939Z","steps":["trace[1426620810] 'agreement among raft nodes before linearized reading' (duration: 4.095416688s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.468978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.373278Z","time spent":"4.095693006s","remote":"127.0.0.1:45358","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-22T14:37:54.46912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.966927551s","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-22T14:37:54.469184Z","caller":"traceutil/trace.go:171","msg":"trace[227453610] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3216; }","duration":"2.967063236s","start":"2026-08-22T14:37:51.502105Z","end":"2026-08-22T14:37:54.469169Z","steps":["trace[227453610] 'agreement among raft nodes before linearized reading' (duration: 2.96685391s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:54.469191Z","caller":"traceutil/trace.go:171","msg":"trace[555184240] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"172.156399ms","start":"2026-08-22T14:37:54.297015Z","end":"2026-08-22T14:37:54.469171Z","steps":["trace[555184240] 'process raft request' (duration: 63.835619ms)","trace[555184240] 'compare' (duration: 107.422417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:54.469216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:51.502063Z","time spent":"2.967145828s","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-22T14:37:54.46922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.012907832s","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-22T14:37:54.469347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671587481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T14:37:54.469118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620370724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:37:54.469364Z","caller":"traceutil/trace.go:171","msg":"trace[510399568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3216; }","duration":"4.013100267s","start":"2026-08-22T14:37:50.456256Z","end":"2026-08-22T14:37:54.469356Z","steps":["trace[510399568] 'agreement among raft nodes before linearized reading' (duration: 4.012902172s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:54.469386Z","caller":"traceutil/trace.go:171","msg":"trace[834150373] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3216; }","duration":"1.620699782s","start":"2026-08-22T14:37:52.848678Z","end":"2026-08-22T14:37:54.469378Z","steps":["trace[834150373] 'agreement among raft nodes before linearized reading' (duration: 1.620257901s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:54.469373Z","caller":"traceutil/trace.go:171","msg":"trace[1419360247] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:3216; }","duration":"2.671654113s","start":"2026-08-22T14:37:51.797711Z","end":"2026-08-22T14:37:54.469366Z","steps":["trace[1419360247] 'agreement among raft nodes before linearized reading' (duration: 2.671579861s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.848659Z","time spent":"1.620751674s","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T14:37:54.469427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:51.797694Z","time spent":"2.671726774s","remote":"127.0.0.1:57880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:37:54.469424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.420618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:54.469458Z","caller":"traceutil/trace.go:171","msg":"trace[1425883636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3216; }","duration":"943.48297ms","start":"2026-08-22T14:37:53.525963Z","end":"2026-08-22T14:37:54.469446Z","steps":["trace[1425883636] 'agreement among raft nodes before linearized reading' (duration: 943.433378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:53.52595Z","time spent":"943.544021ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:37:54.46939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.456232Z","time spent":"4.013150478s","remote":"127.0.0.1:45358","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-22T14:37:54.469518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.095993383s","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-22T14:37:54.469562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.270559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:37:54.469587Z","caller":"traceutil/trace.go:171","msg":"trace[939906904] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:3216; }","duration":"158.337301ms","start":"2026-08-22T14:37:54.311243Z","end":"2026-08-22T14:37:54.46958Z","steps":["trace[939906904] 'agreement among raft nodes before linearized reading' (duration: 158.29325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.039263404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-22T14:37:54.469583Z","caller":"traceutil/trace.go:171","msg":"trace[794204727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3216; }","duration":"4.096072945s","start":"2026-08-22T14:37:50.373493Z","end":"2026-08-22T14:37:54.469566Z","steps":["trace[794204727] 'agreement among raft nodes before linearized reading' (duration: 4.095855979s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.373488Z","time spent":"4.09625696s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:37:54.469756Z","caller":"traceutil/trace.go:171","msg":"trace[339008682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3216; }","duration":"4.039344326s","start":"2026-08-22T14:37:50.430391Z","end":"2026-08-22T14:37:54.469736Z","steps":["trace[339008682] 'agreement among raft nodes before linearized reading' (duration: 4.039241223s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:50.430364Z","time spent":"4.039416378s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:37:54.469807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.131761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:54.46983Z","caller":"traceutil/trace.go:171","msg":"trace[1068236750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3216; }","duration":"169.189871ms","start":"2026-08-22T14:37:54.300634Z","end":"2026-08-22T14:37:54.469824Z","steps":["trace[1068236750] 'agreement among raft nodes before linearized reading' (duration: 169.15285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.470042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.670855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:54.470267Z","caller":"traceutil/trace.go:171","msg":"trace[2002797743] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3216; }","duration":"573.970402ms","start":"2026-08-22T14:37:53.89627Z","end":"2026-08-22T14:37:54.47024Z","steps":["trace[2002797743] 'agreement among raft nodes before linearized reading' (duration: 573.067379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.469618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.039387206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:54.470455Z","caller":"traceutil/trace.go:171","msg":"trace[299892075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:3216; }","duration":"2.040221518s","start":"2026-08-22T14:37:52.430188Z","end":"2026-08-22T14:37:54.470409Z","steps":["trace[299892075] 'agreement among raft nodes before linearized reading' (duration: 2.039408816s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.470519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:52.430167Z","time spent":"2.040328221s","remote":"127.0.0.1:51818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:37:54.470188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077735241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-08-22T14:37:54.470614Z","caller":"traceutil/trace.go:171","msg":"trace[1724636393] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3216; }","duration":"1.078212094s","start":"2026-08-22T14:37:53.392394Z","end":"2026-08-22T14:37:54.470606Z","steps":["trace[1724636393] 'agreement among raft nodes before linearized reading' (duration: 1.077459144s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:54.470638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:53.392366Z","time spent":"1.078265476s","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-08-22T14:37:54.471769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:53.896245Z","time spent":"575.483972ms","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:37:54.674186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.996018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-22T14:37:54.67434Z","caller":"traceutil/trace.go:171","msg":"trace[1939943293] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3221; }","duration":"104.190882ms","start":"2026-08-22T14:37:54.570115Z","end":"2026-08-22T14:37:54.674306Z","steps":["trace[1939943293] 'range keys from in-memory index tree' (duration: 103.720571ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:56.464416Z","caller":"traceutil/trace.go:171","msg":"trace[1649415592] transaction","detail":"{read_only:false; response_revision:3226; number_of_response:1; }","duration":"241.037311ms","start":"2026-08-22T14:37:56.223348Z","end":"2026-08-22T14:37:56.464385Z","steps":["trace[1649415592] 'process raft request' (duration: 240.938449ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:56.464452Z","caller":"traceutil/trace.go:171","msg":"trace[1345107286] linearizableReadLoop","detail":"{readStateIndex:3349; appliedIndex:3346; }","duration":"173.15743ms","start":"2026-08-22T14:37:56.29127Z","end":"2026-08-22T14:37:56.464427Z","steps":["trace[1345107286] 'read index received' (duration: 118.581741ms)","trace[1345107286] 'applied index is now lower than readState.Index' (duration: 54.574619ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:56.464597Z","caller":"traceutil/trace.go:171","msg":"trace[60386258] transaction","detail":"{read_only:false; response_revision:3225; number_of_response:1; }","duration":"242.58903ms","start":"2026-08-22T14:37:56.221881Z","end":"2026-08-22T14:37:56.46447Z","steps":["trace[60386258] 'process raft request' (duration: 188.011291ms)","trace[60386258] 'compare' (duration: 54.127988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:56.464668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.340684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:56.464744Z","caller":"traceutil/trace.go:171","msg":"trace[784122474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3227; }","duration":"173.518ms","start":"2026-08-22T14:37:56.291213Z","end":"2026-08-22T14:37:56.464731Z","steps":["trace[784122474] 'agreement among raft nodes before linearized reading' (duration: 173.302534ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:56.464774Z","caller":"traceutil/trace.go:171","msg":"trace[176503917] transaction","detail":"{read_only:false; response_revision:3227; number_of_response:1; }","duration":"220.295099ms","start":"2026-08-22T14:37:56.244469Z","end":"2026-08-22T14:37:56.464764Z","steps":["trace[176503917] 'process raft request' (duration: 219.874268ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:58.628181Z","caller":"traceutil/trace.go:171","msg":"trace[1093404678] transaction","detail":"{read_only:false; response_revision:3237; number_of_response:1; }","duration":"157.832598ms","start":"2026-08-22T14:37:58.470312Z","end":"2026-08-22T14:37:58.628145Z","steps":["trace[1093404678] 'process raft request' (duration: 129.175777ms)","trace[1093404678] 'compare' (duration: 28.443285ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:58.628365Z","caller":"traceutil/trace.go:171","msg":"trace[935406095] transaction","detail":"{read_only:false; response_revision:3238; number_of_response:1; }","duration":"155.427256ms","start":"2026-08-22T14:37:58.472904Z","end":"2026-08-22T14:37:58.628331Z","steps":["trace[935406095] 'process raft request' (duration: 155.153799ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:58.867454Z","caller":"traceutil/trace.go:171","msg":"trace[278587836] transaction","detail":"{read_only:false; response_revision:3239; number_of_response:1; }","duration":"233.487592ms","start":"2026-08-22T14:37:58.633944Z","end":"2026-08-22T14:37:58.867431Z","steps":["trace[278587836] 'process raft request' (duration: 157.98144ms)","trace[278587836] 'compare' (duration: 75.190814ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:58.867788Z","caller":"traceutil/trace.go:171","msg":"trace[1713505071] linearizableReadLoop","detail":"{readStateIndex:3362; appliedIndex:3361; }","duration":"203.826087ms","start":"2026-08-22T14:37:58.663422Z","end":"2026-08-22T14:37:58.867248Z","steps":["trace[1713505071] 'read index received' (duration: 128.458649ms)","trace[1713505071] 'applied index is now lower than readState.Index' (duration: 75.366168ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:58.868147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.985511ms","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-22T14:37:58.868191Z","caller":"traceutil/trace.go:171","msg":"trace[1656270302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3239; }","duration":"204.091454ms","start":"2026-08-22T14:37:58.664089Z","end":"2026-08-22T14:37:58.868181Z","steps":["trace[1656270302] 'agreement among raft nodes before linearized reading' (duration: 203.93955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:58.86826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.73843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:37:58.868418Z","caller":"traceutil/trace.go:171","msg":"trace[1730262018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3239; }","duration":"205.009827ms","start":"2026-08-22T14:37:58.663385Z","end":"2026-08-22T14:37:58.868395Z","steps":["trace[1730262018] 'agreement among raft nodes before linearized reading' (duration: 204.436983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:58.868586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.07682ms","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-22T14:37:58.868627Z","caller":"traceutil/trace.go:171","msg":"trace[1518231991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3239; }","duration":"205.134962ms","start":"2026-08-22T14:37:58.663482Z","end":"2026-08-22T14:37:58.868617Z","steps":["trace[1518231991] 'agreement among raft nodes before linearized reading' (duration: 204.456004ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:00.376902Z","caller":"traceutil/trace.go:171","msg":"trace[1828471525] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"105.221358ms","start":"2026-08-22T14:38:00.271633Z","end":"2026-08-22T14:38:00.376855Z","steps":["trace[1828471525] 'process raft request' (duration: 104.946511ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:01.219315Z","caller":"traceutil/trace.go:171","msg":"trace[1202472789] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"257.847954ms","start":"2026-08-22T14:38:00.96144Z","end":"2026-08-22T14:38:01.219288Z","steps":["trace[1202472789] 'process raft request' (duration: 257.69713ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:01.231219Z","caller":"traceutil/trace.go:171","msg":"trace[1629988223] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"268.322237ms","start":"2026-08-22T14:38:00.962869Z","end":"2026-08-22T14:38:01.231191Z","steps":["trace[1629988223] 'process raft request' (duration: 268.278856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:01.231417Z","caller":"traceutil/trace.go:171","msg":"trace[1623240491] transaction","detail":"{read_only:false; response_revision:3251; number_of_response:1; }","duration":"268.83227ms","start":"2026-08-22T14:38:00.962441Z","end":"2026-08-22T14:38:01.231273Z","steps":["trace[1623240491] 'process raft request' (duration: 268.607825ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:02.569137Z","caller":"traceutil/trace.go:171","msg":"trace[1132668355] transaction","detail":"{read_only:false; response_revision:3256; number_of_response:1; }","duration":"186.623648ms","start":"2026-08-22T14:38:02.382481Z","end":"2026-08-22T14:38:02.569105Z","steps":["trace[1132668355] 'process raft request' (duration: 186.412573ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:03.407527Z","caller":"traceutil/trace.go:171","msg":"trace[1597954805] linearizableReadLoop","detail":"{readStateIndex:3385; appliedIndex:3384; }","duration":"172.461008ms","start":"2026-08-22T14:38:03.235036Z","end":"2026-08-22T14:38:03.407497Z","steps":["trace[1597954805] 'read index received' (duration: 172.290824ms)","trace[1597954805] 'applied index is now lower than readState.Index' (duration: 168.704µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:38:03.40781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.309774ms","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-22T14:38:03.407814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.757975ms","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-22T14:38:03.407898Z","caller":"traceutil/trace.go:171","msg":"trace[191262628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3261; }","duration":"172.91707ms","start":"2026-08-22T14:38:03.234962Z","end":"2026-08-22T14:38:03.407879Z","steps":["trace[191262628] 'agreement among raft nodes before linearized reading' (duration: 172.642973ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:03.40793Z","caller":"traceutil/trace.go:171","msg":"trace[2030195832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3261; }","duration":"172.389747ms","start":"2026-08-22T14:38:03.235452Z","end":"2026-08-22T14:38:03.407841Z","steps":["trace[2030195832] 'agreement among raft nodes before linearized reading' (duration: 172.211502ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:03.408249Z","caller":"traceutil/trace.go:171","msg":"trace[1928168438] transaction","detail":"{read_only:false; response_revision:3261; number_of_response:1; }","duration":"181.146785ms","start":"2026-08-22T14:38:03.227044Z","end":"2026-08-22T14:38:03.408191Z","steps":["trace[1928168438] 'process raft request' (duration: 180.234863ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:04.91569Z","caller":"traceutil/trace.go:171","msg":"trace[938301358] linearizableReadLoop","detail":"{readStateIndex:3391; appliedIndex:3390; }","duration":"186.666335ms","start":"2026-08-22T14:38:04.728997Z","end":"2026-08-22T14:38:04.915663Z","steps":["trace[938301358] 'read index received' (duration: 186.47735ms)","trace[938301358] 'applied index is now lower than readState.Index' (duration: 187.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:38:04.915838Z","caller":"traceutil/trace.go:171","msg":"trace[1489269832] transaction","detail":"{read_only:false; response_revision:3267; number_of_response:1; }","duration":"196.723655ms","start":"2026-08-22T14:38:04.719097Z","end":"2026-08-22T14:38:04.91582Z","steps":["trace[1489269832] 'process raft request' (duration: 196.405398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:04.916217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.032612ms","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":"warn","ts":"2026-08-22T14:38:04.916293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.266929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:38:04.916339Z","caller":"traceutil/trace.go:171","msg":"trace[1791397670] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3267; }","duration":"119.193765ms","start":"2026-08-22T14:38:04.797118Z","end":"2026-08-22T14:38:04.916312Z","steps":["trace[1791397670] 'agreement among raft nodes before linearized reading' (duration: 118.99347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:04.916346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.317637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:38:04.916378Z","caller":"traceutil/trace.go:171","msg":"trace[1094279248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3267; }","duration":"187.402283ms","start":"2026-08-22T14:38:04.728955Z","end":"2026-08-22T14:38:04.916357Z","steps":["trace[1094279248] 'agreement among raft nodes before linearized reading' (duration: 187.143817ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:04.916402Z","caller":"traceutil/trace.go:171","msg":"trace[1886670724] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3267; }","duration":"143.426409ms","start":"2026-08-22T14:38:04.77296Z","end":"2026-08-22T14:38:04.916387Z","steps":["trace[1886670724] 'agreement among raft nodes before linearized reading' (duration: 143.158653ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:05.602977Z","caller":"traceutil/trace.go:171","msg":"trace[1715708501] linearizableReadLoop","detail":"{readStateIndex:3396; appliedIndex:3395; }","duration":"111.383311ms","start":"2026-08-22T14:38:05.491566Z","end":"2026-08-22T14:38:05.602949Z","steps":["trace[1715708501] 'read index received' (duration: 101.550058ms)","trace[1715708501] 'applied index is now lower than readState.Index' (duration: 9.832213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:38:05.603266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.67961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T14:38:05.603292Z","caller":"traceutil/trace.go:171","msg":"trace[172670079] transaction","detail":"{read_only:false; response_revision:3272; number_of_response:1; }","duration":"156.121382ms","start":"2026-08-22T14:38:05.446932Z","end":"2026-08-22T14:38:05.603053Z","steps":["trace[172670079] 'process raft request' (duration: 146.248437ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:05.603354Z","caller":"traceutil/trace.go:171","msg":"trace[351005893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3272; }","duration":"111.788562ms","start":"2026-08-22T14:38:05.491547Z","end":"2026-08-22T14:38:05.603336Z","steps":["trace[351005893] 'agreement among raft nodes before linearized reading' (duration: 111.588607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:05.603584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.103322ms","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-22T14:38:05.603627Z","caller":"traceutil/trace.go:171","msg":"trace[772937479] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3272; }","duration":"102.191105ms","start":"2026-08-22T14:38:05.501427Z","end":"2026-08-22T14:38:05.603618Z","steps":["trace[772937479] 'agreement among raft nodes before linearized reading' (duration: 101.972159ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:07.03121Z","caller":"traceutil/trace.go:171","msg":"trace[487134441] transaction","detail":"{read_only:false; response_revision:3277; number_of_response:1; }","duration":"107.046781ms","start":"2026-08-22T14:38:06.924124Z","end":"2026-08-22T14:38:07.031171Z","steps":["trace[487134441] 'process raft request' (duration: 106.811995ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:07.778525Z","caller":"traceutil/trace.go:171","msg":"trace[1804285472] transaction","detail":"{read_only:false; response_revision:3283; number_of_response:1; }","duration":"147.90848ms","start":"2026-08-22T14:38:07.630577Z","end":"2026-08-22T14:38:07.778485Z","steps":["trace[1804285472] 'process raft request' (duration: 147.642174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:08.665908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.519426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:08.666023Z","caller":"traceutil/trace.go:171","msg":"trace[2090574914] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3285; }","duration":"120.65786ms","start":"2026-08-22T14:38:08.545334Z","end":"2026-08-22T14:38:08.665992Z","steps":["trace[2090574914] 'count revisions from in-memory index tree' (duration: 120.401013ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:09.250727Z","caller":"traceutil/trace.go:171","msg":"trace[1736887377] transaction","detail":"{read_only:false; response_revision:3289; number_of_response:1; }","duration":"127.365893ms","start":"2026-08-22T14:38:09.123314Z","end":"2026-08-22T14:38:09.25068Z","steps":["trace[1736887377] 'process raft request' (duration: 124.176244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:25.783966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:26.284967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:26.785541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:27.286317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:27.293058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000362526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-22T14:38:27.293174Z","caller":"traceutil/trace.go:171","msg":"trace[199995154] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00051962s","start":"2026-08-22T14:38:25.29263Z","end":"2026-08-22T14:38:27.293149Z","steps":["trace[199995154] 'agreement among raft nodes before linearized reading' (duration: 2.000378796s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:27.293233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.292621Z","time spent":"2.000601861s","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/22 14:38:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-22T14:38:27.787523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:28.288092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298370880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:38:28.518097Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.769705006s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:38:28.518704Z","caller":"traceutil/trace.go:171","msg":"trace[1546769059] transaction","detail":"{read_only:false; response_revision:3366; number_of_response:1; }","duration":"3.770422703s","start":"2026-08-22T14:38:24.748246Z","end":"2026-08-22T14:38:28.518668Z","steps":["trace[1546769059] 'process raft request' (duration: 3.770230669s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.518877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:24.748209Z","time spent":"3.770570976s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:38:28.521364Z","caller":"traceutil/trace.go:171","msg":"trace[6495856] linearizableReadLoop","detail":"{readStateIndex:3498; appliedIndex:3494; }","duration":"3.237694431s","start":"2026-08-22T14:38:25.283641Z","end":"2026-08-22T14:38:28.521335Z","steps":["trace[6495856] 'read index received' (duration: 3.236175366s)","trace[6495856] 'applied index is now lower than readState.Index' (duration: 1.517545ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:38:28.5215Z","caller":"traceutil/trace.go:171","msg":"trace[996272502] transaction","detail":"{read_only:false; response_revision:3368; number_of_response:1; }","duration":"3.374968513s","start":"2026-08-22T14:38:25.146504Z","end":"2026-08-22T14:38:28.521473Z","steps":["trace[996272502] 'process raft request' (duration: 3.374698647s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:28.521571Z","caller":"traceutil/trace.go:171","msg":"trace[214360127] transaction","detail":"{read_only:false; response_revision:3370; number_of_response:1; }","duration":"834.089007ms","start":"2026-08-22T14:38:27.687473Z","end":"2026-08-22T14:38:28.521562Z","steps":["trace[214360127] 'process raft request' (duration: 833.815751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.521647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.146472Z","time spent":"3.375112297s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:38:28.52172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:27.687441Z","time spent":"834.173599ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:38:28.522085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.238449268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:38:28.52213Z","caller":"traceutil/trace.go:171","msg":"trace[2021215813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3370; }","duration":"3.238494709s","start":"2026-08-22T14:38:25.283625Z","end":"2026-08-22T14:38:28.522119Z","steps":["trace[2021215813] 'agreement among raft nodes before linearized reading' (duration: 3.238239343s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.283564Z","time spent":"3.238596392s","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-22T14:38:28.522241Z","caller":"traceutil/trace.go:171","msg":"trace[1155864212] transaction","detail":"{read_only:false; response_revision:3367; number_of_response:1; }","duration":"3.566443461s","start":"2026-08-22T14:38:24.955777Z","end":"2026-08-22T14:38:28.52222Z","steps":["trace[1155864212] 'process raft request' (duration: 3.565264173s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:24.955744Z","time spent":"3.566531133s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:38:28.522399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.190280715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T14:38:28.522488Z","caller":"traceutil/trace.go:171","msg":"trace[262933748] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3370; }","duration":"3.190414598s","start":"2026-08-22T14:38:25.332049Z","end":"2026-08-22T14:38:28.522463Z","steps":["trace[262933748] 'agreement among raft nodes before linearized reading' (duration: 3.190162833s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.332027Z","time spent":"3.19051183s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:38:28.522589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.141446745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:28.52262Z","caller":"traceutil/trace.go:171","msg":"trace[78883016] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:3370; }","duration":"2.141532817s","start":"2026-08-22T14:38:26.38108Z","end":"2026-08-22T14:38:28.522613Z","steps":["trace[78883016] 'agreement among raft nodes before linearized reading' (duration: 2.141427384s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:26.381051Z","time spent":"2.141585708s","remote":"127.0.0.1:45304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:38:28.522718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.8266049s","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-22T14:38:28.522762Z","caller":"traceutil/trace.go:171","msg":"trace[1081097712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3370; }","duration":"2.826675052s","start":"2026-08-22T14:38:25.696079Z","end":"2026-08-22T14:38:28.522754Z","steps":["trace[1081097712] 'agreement among raft nodes before linearized reading' (duration: 2.826545989s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.521238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-22T14:38:28.52279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.696066Z","time spent":"2.826718373s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:38:28.522804Z","caller":"traceutil/trace.go:171","msg":"trace[224508022] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:3370; }","duration":"814.574869ms","start":"2026-08-22T14:38:27.708223Z","end":"2026-08-22T14:38:28.522798Z","steps":["trace[224508022] 'agreement among raft nodes before linearized reading' (duration: 814.509927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.52283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:27.708208Z","time spent":"814.61698ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-08-22T14:38:28.522832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.65569089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:38:28.522862Z","caller":"traceutil/trace.go:171","msg":"trace[2039403192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3370; }","duration":"2.655746682s","start":"2026-08-22T14:38:25.867108Z","end":"2026-08-22T14:38:28.522855Z","steps":["trace[2039403192] 'agreement among raft nodes before linearized reading' (duration: 2.65566756s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.867097Z","time spent":"2.655790693s","remote":"127.0.0.1:45358","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-22T14:38:28.522909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.87811951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T14:38:28.522953Z","caller":"traceutil/trace.go:171","msg":"trace[238027920] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3370; }","duration":"2.878221312s","start":"2026-08-22T14:38:25.644723Z","end":"2026-08-22T14:38:28.522944Z","steps":["trace[238027920] 'agreement among raft nodes before linearized reading' (duration: 2.877936786s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.522994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.644685Z","time spent":"2.878299024s","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":297,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:38:28.523027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.271436919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T14:38:28.523041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168297588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:28.523057Z","caller":"traceutil/trace.go:171","msg":"trace[106307767] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3370; }","duration":"2.271514381s","start":"2026-08-22T14:38:26.251536Z","end":"2026-08-22T14:38:28.523051Z","steps":["trace[106307767] 'agreement among raft nodes before linearized reading' (duration: 2.271446989s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:28.523075Z","caller":"traceutil/trace.go:171","msg":"trace[363511044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:3370; }","duration":"1.168391591s","start":"2026-08-22T14:38:27.354673Z","end":"2026-08-22T14:38:28.523064Z","steps":["trace[363511044] 'agreement among raft nodes before linearized reading' (duration: 1.16834143s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:26.251512Z","time spent":"2.271572312s","remote":"127.0.0.1:50470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:38:28.523109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:27.354648Z","time spent":"1.168451552s","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:38:28.523211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.021586316s","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-22T14:38:28.523251Z","caller":"traceutil/trace.go:171","msg":"trace[1424198801] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3370; }","duration":"3.021652767s","start":"2026-08-22T14:38:25.501588Z","end":"2026-08-22T14:38:28.523241Z","steps":["trace[1424198801] 'agreement among raft nodes before linearized reading' (duration: 3.021549565s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971338097s","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-22T14:38:28.523265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229824561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:38:28.523276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.187782847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-22T14:38:28.522926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.814591633s","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-22T14:38:28.52329Z","caller":"traceutil/trace.go:171","msg":"trace[508908951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3370; }","duration":"1.971632095s","start":"2026-08-22T14:38:26.55165Z","end":"2026-08-22T14:38:28.523282Z","steps":["trace[508908951] 'agreement among raft nodes before linearized reading' (duration: 1.971339528s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:28.523308Z","caller":"traceutil/trace.go:171","msg":"trace[808658846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3370; }","duration":"3.187814297s","start":"2026-08-22T14:38:25.335486Z","end":"2026-08-22T14:38:28.5233Z","steps":["trace[808658846] 'agreement among raft nodes before linearized reading' (duration: 3.187744786s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:28.523312Z","caller":"traceutil/trace.go:171","msg":"trace[1787818846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3370; }","duration":"2.814999092s","start":"2026-08-22T14:38:25.708306Z","end":"2026-08-22T14:38:28.523305Z","steps":["trace[1787818846] 'agreement among raft nodes before linearized reading' (duration: 2.814566343s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:26.551618Z","time spent":"1.971694915s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:38:28.523335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.335444Z","time spent":"3.187886539s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:38:28.523342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.708294Z","time spent":"2.815037483s","remote":"127.0.0.1:45358","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-22T14:38:28.523277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.501581Z","time spent":"3.021689168s","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-22T14:38:28.523332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510337911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:28.523535Z","caller":"traceutil/trace.go:171","msg":"trace[519191043] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3370; }","duration":"1.510568215s","start":"2026-08-22T14:38:27.012953Z","end":"2026-08-22T14:38:28.523521Z","steps":["trace[519191043] 'agreement among raft nodes before linearized reading' (duration: 1.51035702s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:27.012934Z","time spent":"1.510624457s","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:38:28.521525Z","caller":"traceutil/trace.go:171","msg":"trace[1784790822] transaction","detail":"{read_only:false; response_revision:3369; number_of_response:1; }","duration":"2.684925597s","start":"2026-08-22T14:38:25.836587Z","end":"2026-08-22T14:38:28.521513Z","steps":["trace[1784790822] 'process raft request' (duration: 2.684661761s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:25.836566Z","time spent":"2.687067296s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:38:28.522996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629087444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:38:28.523817Z","caller":"traceutil/trace.go:171","msg":"trace[1705229765] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3370; }","duration":"1.629968884s","start":"2026-08-22T14:38:26.893837Z","end":"2026-08-22T14:38:28.523806Z","steps":["trace[1705229765] 'agreement among raft nodes before linearized reading' (duration: 1.629109934s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:28.523296Z","caller":"traceutil/trace.go:171","msg":"trace[245360047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3370; }","duration":"1.229909153s","start":"2026-08-22T14:38:27.293378Z","end":"2026-08-22T14:38:28.523287Z","steps":["trace[245360047] 'agreement among raft nodes before linearized reading' (duration: 1.229861452s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:28.523863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:26.893805Z","time spent":"1.630045386s","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":24,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:38:28.523917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:38:27.29335Z","time spent":"1.230556629s","remote":"127.0.0.1:45094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:40:40.944399Z","caller":"traceutil/trace.go:171","msg":"trace[1070104469] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"109.429871ms","start":"2026-08-22T14:40:40.834939Z","end":"2026-08-22T14:40:40.944368Z","steps":["trace[1070104469] 'process raft request' (duration: 109.228126ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:40.949471Z","caller":"traceutil/trace.go:171","msg":"trace[1229436574] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"100.880667ms","start":"2026-08-22T14:40:40.848547Z","end":"2026-08-22T14:40:40.949428Z","steps":["trace[1229436574] 'process raft request' (duration: 100.637693ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:44.15358Z","caller":"traceutil/trace.go:171","msg":"trace[1419814975] linearizableReadLoop","detail":"{readStateIndex:4836; appliedIndex:4835; }","duration":"106.346207ms","start":"2026-08-22T14:40:44.047171Z","end":"2026-08-22T14:40:44.153518Z","steps":["trace[1419814975] 'read index received' (duration: 28.186046ms)","trace[1419814975] 'applied index is now lower than readState.Index' (duration: 78.157791ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:44.154056Z","caller":"traceutil/trace.go:171","msg":"trace[1991562672] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"106.917136ms","start":"2026-08-22T14:40:44.046975Z","end":"2026-08-22T14:40:44.153892Z","steps":["trace[1991562672] 'process raft request' (duration: 28.44981ms)","trace[1991562672] 'compare' (duration: 77.636503ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:44.154714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.477865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:40:44.154898Z","caller":"traceutil/trace.go:171","msg":"trace[1475177707] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:4657; }","duration":"107.66804ms","start":"2026-08-22T14:40:44.047118Z","end":"2026-08-22T14:40:44.154786Z","steps":["trace[1475177707] 'agreement among raft nodes before linearized reading' (duration: 107.412075ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:44.442897Z","caller":"traceutil/trace.go:171","msg":"trace[1914955488] linearizableReadLoop","detail":"{readStateIndex:4839; appliedIndex:4836; }","duration":"289.059465ms","start":"2026-08-22T14:40:44.153813Z","end":"2026-08-22T14:40:44.442873Z","steps":["trace[1914955488] 'read index received' (duration: 206.585943ms)","trace[1914955488] 'applied index is now lower than readState.Index' (duration: 82.472102ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:44.442932Z","caller":"traceutil/trace.go:171","msg":"trace[257923544] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"394.182781ms","start":"2026-08-22T14:40:44.048706Z","end":"2026-08-22T14:40:44.442889Z","steps":["trace[257923544] 'process raft request' (duration: 311.849822ms)","trace[257923544] 'compare' (duration: 82.037175ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:44.443347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.813121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:40:44.443329Z","caller":"traceutil/trace.go:171","msg":"trace[690476220] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"387.222208ms","start":"2026-08-22T14:40:44.056052Z","end":"2026-08-22T14:40:44.443275Z","steps":["trace[690476220] 'process raft request' (duration: 386.684208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:44.443421Z","caller":"traceutil/trace.go:171","msg":"trace[445251676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4660; }","duration":"150.936934ms","start":"2026-08-22T14:40:44.292461Z","end":"2026-08-22T14:40:44.443398Z","steps":["trace[445251676] 'agreement among raft nodes before linearized reading' (duration: 150.781731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.443389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.23107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:40:44.443492Z","caller":"traceutil/trace.go:171","msg":"trace[1309977097] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:4660; }","duration":"283.407173ms","start":"2026-08-22T14:40:44.160061Z","end":"2026-08-22T14:40:44.443468Z","steps":["trace[1309977097] 'agreement among raft nodes before linearized reading' (duration: 283.202359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.443599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:44.056022Z","time spent":"387.413651ms","remote":"127.0.0.1:45564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:40:44.443841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.391145ms","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-22T14:40:44.443879Z","caller":"traceutil/trace.go:171","msg":"trace[2004545869] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4660; }","duration":"222.515946ms","start":"2026-08-22T14:40:44.221352Z","end":"2026-08-22T14:40:44.443868Z","steps":["trace[2004545869] 'agreement among raft nodes before linearized reading' (duration: 222.422245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.443954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.930158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-22T14:40:44.444001Z","caller":"traceutil/trace.go:171","msg":"trace[704168609] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4660; }","duration":"393.971439ms","start":"2026-08-22T14:40:44.050011Z","end":"2026-08-22T14:40:44.443983Z","steps":["trace[704168609] 'agreement among raft nodes before linearized reading' (duration: 393.815367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.444032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:44.049983Z","time spent":"394.0414ms","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-08-22T14:40:44.444258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:44.048689Z","time spent":"394.341804ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1188,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:40:44.444313Z","caller":"traceutil/trace.go:171","msg":"trace[810414747] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"388.065982ms","start":"2026-08-22T14:40:44.056083Z","end":"2026-08-22T14:40:44.444148Z","steps":["trace[810414747] 'process raft request' (duration: 386.727339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.444455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:44.056057Z","time spent":"388.311775ms","remote":"127.0.0.1:45548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:40:44.44451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.019357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-08-22T14:40:44.444591Z","caller":"traceutil/trace.go:171","msg":"trace[255185630] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4660; }","duration":"222.13394ms","start":"2026-08-22T14:40:44.222438Z","end":"2026-08-22T14:40:44.444572Z","steps":["trace[255185630] 'agreement among raft nodes before linearized reading' (duration: 220.993691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.445756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.493785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46955"} {"level":"info","ts":"2026-08-22T14:40:44.445782Z","caller":"traceutil/trace.go:171","msg":"trace[1381222232] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4660; }","duration":"386.521965ms","start":"2026-08-22T14:40:44.059253Z","end":"2026-08-22T14:40:44.445775Z","steps":["trace[1381222232] 'agreement among raft nodes before linearized reading' (duration: 386.379953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:44.445799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:44.059243Z","time spent":"386.550785ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":46978,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-08-22T14:40:49.578905Z","caller":"traceutil/trace.go:171","msg":"trace[811855104] linearizableReadLoop","detail":"{readStateIndex:4979; appliedIndex:4978; }","duration":"230.364426ms","start":"2026-08-22T14:40:49.348512Z","end":"2026-08-22T14:40:49.578877Z","steps":["trace[811855104] 'read index received' (duration: 153.828131ms)","trace[811855104] 'applied index is now lower than readState.Index' (duration: 76.534625ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:49.578965Z","caller":"traceutil/trace.go:171","msg":"trace[1303008188] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"232.191276ms","start":"2026-08-22T14:40:49.346732Z","end":"2026-08-22T14:40:49.578923Z","steps":["trace[1303008188] 'process raft request' (duration: 155.58997ms)","trace[1303008188] 'compare' (duration: 76.421933ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:49.57919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.652661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T14:40:49.579238Z","caller":"traceutil/trace.go:171","msg":"trace[1385779946] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4796; }","duration":"230.732922ms","start":"2026-08-22T14:40:49.348494Z","end":"2026-08-22T14:40:49.579227Z","steps":["trace[1385779946] 'agreement among raft nodes before linearized reading' (duration: 230.545879ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.589734Z","caller":"traceutil/trace.go:171","msg":"trace[545153650] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"236.907514ms","start":"2026-08-22T14:40:49.352803Z","end":"2026-08-22T14:40:49.589711Z","steps":["trace[545153650] 'process raft request' (duration: 236.800932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.590151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.378913ms","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-22T14:40:49.590195Z","caller":"traceutil/trace.go:171","msg":"trace[1802389443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4798; }","duration":"123.440444ms","start":"2026-08-22T14:40:49.466745Z","end":"2026-08-22T14:40:49.590185Z","steps":["trace[1802389443] 'agreement among raft nodes before linearized reading' (duration: 123.256341ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.590315Z","caller":"traceutil/trace.go:171","msg":"trace[243090668] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4798; }","duration":"237.176308ms","start":"2026-08-22T14:40:49.35313Z","end":"2026-08-22T14:40:49.590306Z","steps":["trace[243090668] 'process raft request' (duration: 236.542708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.590425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.707674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T14:40:49.590444Z","caller":"traceutil/trace.go:171","msg":"trace[943342518] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4798; }","duration":"116.748064ms","start":"2026-08-22T14:40:49.473688Z","end":"2026-08-22T14:40:49.590437Z","steps":["trace[943342518] 'agreement among raft nodes before linearized reading' (duration: 116.688043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.590679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.308222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:40:49.590716Z","caller":"traceutil/trace.go:171","msg":"trace[1270752379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4798; }","duration":"159.374382ms","start":"2026-08-22T14:40:49.431334Z","end":"2026-08-22T14:40:49.590709Z","steps":["trace[1270752379] 'agreement among raft nodes before linearized reading' (duration: 159.280661ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.590785Z","caller":"traceutil/trace.go:171","msg":"trace[2056318861] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"241.199694ms","start":"2026-08-22T14:40:49.349571Z","end":"2026-08-22T14:40:49.590771Z","steps":["trace[2056318861] 'process raft request' (duration: 239.915014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.590866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.21855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:58703"} {"level":"warn","ts":"2026-08-22T14:40:49.590916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.098351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:40:49.590939Z","caller":"traceutil/trace.go:171","msg":"trace[1579611848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4798; }","duration":"219.138912ms","start":"2026-08-22T14:40:49.371795Z","end":"2026-08-22T14:40:49.590934Z","steps":["trace[1579611848] 'agreement among raft nodes before linearized reading' (duration: 219.077501ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.590937Z","caller":"traceutil/trace.go:171","msg":"trace[1241274432] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4798; }","duration":"242.314622ms","start":"2026-08-22T14:40:49.348607Z","end":"2026-08-22T14:40:49.590922Z","steps":["trace[1241274432] 'agreement among raft nodes before linearized reading' (duration: 241.971926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.591295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.195736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-08-22T14:40:49.591393Z","caller":"traceutil/trace.go:171","msg":"trace[2101675451] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4798; }","duration":"146.379019ms","start":"2026-08-22T14:40:49.444998Z","end":"2026-08-22T14:40:49.591377Z","steps":["trace[2101675451] 'agreement among raft nodes before linearized reading' (duration: 145.066128ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.784622Z","caller":"traceutil/trace.go:171","msg":"trace[1303822918] linearizableReadLoop","detail":"{readStateIndex:4988; appliedIndex:4987; }","duration":"128.338763ms","start":"2026-08-22T14:40:49.656255Z","end":"2026-08-22T14:40:49.784593Z","steps":["trace[1303822918] 'read index received' (duration: 46.148217ms)","trace[1303822918] 'applied index is now lower than readState.Index' (duration: 82.188746ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:49.784677Z","caller":"traceutil/trace.go:171","msg":"trace[1631278686] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"129.006074ms","start":"2026-08-22T14:40:49.655641Z","end":"2026-08-22T14:40:49.784647Z","steps":["trace[1631278686] 'process raft request' (duration: 46.528203ms)","trace[1631278686] 'compare' (duration: 82.286048ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:49.784935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.646369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-22T14:40:49.784986Z","caller":"traceutil/trace.go:171","msg":"trace[901164684] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4804; }","duration":"128.72911ms","start":"2026-08-22T14:40:49.656244Z","end":"2026-08-22T14:40:49.784973Z","steps":["trace[901164684] 'agreement among raft nodes before linearized reading' (duration: 128.509147ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.940912Z","caller":"traceutil/trace.go:171","msg":"trace[757062053] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"256.566816ms","start":"2026-08-22T14:40:49.684316Z","end":"2026-08-22T14:40:49.940883Z","steps":["trace[757062053] 'process raft request' (duration: 256.413564ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.941046Z","caller":"traceutil/trace.go:171","msg":"trace[1248860903] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"278.301642ms","start":"2026-08-22T14:40:49.662714Z","end":"2026-08-22T14:40:49.941016Z","steps":["trace[1248860903] 'process raft request' (duration: 238.51583ms)","trace[1248860903] 'compare' (duration: 39.365645ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:49.965103Z","caller":"traceutil/trace.go:171","msg":"trace[1829012649] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4988; }","duration":"180.364677ms","start":"2026-08-22T14:40:49.784718Z","end":"2026-08-22T14:40:49.965083Z","steps":["trace[1829012649] 'read index received' (duration: 116.49453ms)","trace[1829012649] 'applied index is now lower than readState.Index' (duration: 63.869217ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:49.965314Z","caller":"traceutil/trace.go:171","msg":"trace[397761115] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"172.733962ms","start":"2026-08-22T14:40:49.79255Z","end":"2026-08-22T14:40:49.965284Z","steps":["trace[397761115] 'process raft request' (duration: 172.428467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.965586Z","caller":"traceutil/trace.go:171","msg":"trace[787756730] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"172.448207ms","start":"2026-08-22T14:40:49.79313Z","end":"2026-08-22T14:40:49.965578Z","steps":["trace[787756730] 'process raft request' (duration: 171.902259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.965781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.075601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:40:49.965845Z","caller":"traceutil/trace.go:171","msg":"trace[1961143998] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4810; }","duration":"205.209754ms","start":"2026-08-22T14:40:49.760625Z","end":"2026-08-22T14:40:49.965835Z","steps":["trace[1961143998] 'agreement among raft nodes before linearized reading' (duration: 205.104412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.966055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.22216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-22T14:40:49.966116Z","caller":"traceutil/trace.go:171","msg":"trace[401789493] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4810; }","duration":"278.280761ms","start":"2026-08-22T14:40:49.687825Z","end":"2026-08-22T14:40:49.966106Z","steps":["trace[401789493] 'agreement among raft nodes before linearized reading' (duration: 278.149999ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.968618Z","caller":"traceutil/trace.go:171","msg":"trace[1044407278] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"177.033332ms","start":"2026-08-22T14:40:49.79155Z","end":"2026-08-22T14:40:49.968583Z","steps":["trace[1044407278] 'process raft request' (duration: 173.363622ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:49.969125Z","caller":"traceutil/trace.go:171","msg":"trace[1594493727] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"202.173744ms","start":"2026-08-22T14:40:49.766937Z","end":"2026-08-22T14:40:49.96911Z","steps":["trace[1594493727] 'process raft request' (duration: 197.789983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:49.979183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.019455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-22T14:40:49.979279Z","caller":"traceutil/trace.go:171","msg":"trace[67568940] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4812; }","duration":"190.157057ms","start":"2026-08-22T14:40:49.789099Z","end":"2026-08-22T14:40:49.979257Z","steps":["trace[67568940] 'agreement among raft nodes before linearized reading' (duration: 189.861522ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:55.781484Z","caller":"traceutil/trace.go:171","msg":"trace[1021510532] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"132.205087ms","start":"2026-08-22T14:40:55.649236Z","end":"2026-08-22T14:40:55.781441Z","steps":["trace[1021510532] 'process raft request' (duration: 108.174437ms)","trace[1021510532] 'compare' (duration: 23.830527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:56.332858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814132298374441,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:40:56.580761Z","caller":"traceutil/trace.go:171","msg":"trace[346512107] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"794.242818ms","start":"2026-08-22T14:40:55.78649Z","end":"2026-08-22T14:40:56.580733Z","steps":["trace[346512107] 'process raft request' (duration: 794.052865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:56.581177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:55.786466Z","time spent":"794.436251ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:40:56.701327Z","caller":"traceutil/trace.go:171","msg":"trace[1327791477] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"914.511299ms","start":"2026-08-22T14:40:55.78677Z","end":"2026-08-22T14:40:56.701282Z","steps":["trace[1327791477] 'process raft request' (duration: 858.974018ms)","trace[1327791477] 'compare' (duration: 55.340978ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:56.701521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:55.786757Z","time spent":"914.667621ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:40:56.701538Z","caller":"traceutil/trace.go:171","msg":"trace[2046712568] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"914.728362ms","start":"2026-08-22T14:40:55.78679Z","end":"2026-08-22T14:40:56.701519Z","steps":["trace[2046712568] 'process raft request' (duration: 914.432708ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:56.701748Z","caller":"traceutil/trace.go:171","msg":"trace[1694101058] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5105; }","duration":"877.440157ms","start":"2026-08-22T14:40:55.824281Z","end":"2026-08-22T14:40:56.701721Z","steps":["trace[1694101058] 'read index received' (duration: 756.395774ms)","trace[1694101058] 'applied index is now lower than readState.Index' (duration: 121.042813ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:56.701887Z","caller":"traceutil/trace.go:171","msg":"trace[1888024472] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"574.314636ms","start":"2026-08-22T14:40:56.127562Z","end":"2026-08-22T14:40:56.701876Z","steps":["trace[1888024472] 'process raft request' (duration: 573.884589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:56.701994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:56.127529Z","time spent":"574.395177ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:40:56.702055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.5295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-55f5cf577b-rw5x2\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-08-22T14:40:56.702113Z","caller":"traceutil/trace.go:171","msg":"trace[1691123444] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-55f5cf577b-rw5x2; range_end:; response_count:1; response_revision:4923; }","duration":"574.615142ms","start":"2026-08-22T14:40:56.127482Z","end":"2026-08-22T14:40:56.702097Z","steps":["trace[1691123444] 'agreement among raft nodes before linearized reading' (duration: 574.405517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:56.70215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:56.127459Z","time spent":"574.684532ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11279,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-55f5cf577b-rw5x2\" "} {"level":"warn","ts":"2026-08-22T14:40:56.702386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.110128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12650367-01a5-4ca7-8ede-e20cb2d9dad6\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-22T14:40:56.702438Z","caller":"traceutil/trace.go:171","msg":"trace[1691409973] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12650367-01a5-4ca7-8ede-e20cb2d9dad6; range_end:; response_count:1; response_revision:4923; }","duration":"878.20284ms","start":"2026-08-22T14:40:55.824222Z","end":"2026-08-22T14:40:56.702425Z","steps":["trace[1691409973] 'agreement among raft nodes before linearized reading' (duration: 878.086088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:56.70248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:55.824209Z","time spent":"878.262441ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12650367-01a5-4ca7-8ede-e20cb2d9dad6\" "} {"level":"warn","ts":"2026-08-22T14:40:56.702734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.420786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:40:56.70278Z","caller":"traceutil/trace.go:171","msg":"trace[37827623] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4923; }","duration":"204.501547ms","start":"2026-08-22T14:40:56.498267Z","end":"2026-08-22T14:40:56.702769Z","steps":["trace[37827623] 'agreement among raft nodes before linearized reading' (duration: 204.377845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:40:56.705061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:40:55.786764Z","time spent":"914.852014ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:40:57.404627Z","caller":"traceutil/trace.go:171","msg":"trace[1073110395] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"251.490762ms","start":"2026-08-22T14:40:57.153104Z","end":"2026-08-22T14:40:57.404595Z","steps":["trace[1073110395] 'process raft request' (duration: 251.255508ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:57.523386Z","caller":"traceutil/trace.go:171","msg":"trace[1280483614] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"294.936217ms","start":"2026-08-22T14:40:57.22841Z","end":"2026-08-22T14:40:57.523346Z","steps":["trace[1280483614] 'process raft request' (duration: 294.49412ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:57.523515Z","caller":"traceutil/trace.go:171","msg":"trace[88049244] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"261.527687ms","start":"2026-08-22T14:40:57.261971Z","end":"2026-08-22T14:40:57.523498Z","steps":["trace[88049244] 'process raft request' (duration: 261.337304ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:57.523522Z","caller":"traceutil/trace.go:171","msg":"trace[403718627] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"264.005575ms","start":"2026-08-22T14:40:57.259468Z","end":"2026-08-22T14:40:57.523473Z","steps":["trace[403718627] 'process raft request' (duration: 263.6635ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:57.523399Z","caller":"traceutil/trace.go:171","msg":"trace[863601441] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5113; }","duration":"231.851456ms","start":"2026-08-22T14:40:57.291522Z","end":"2026-08-22T14:40:57.523374Z","steps":["trace[863601441] 'read index received' (duration: 113.01626ms)","trace[863601441] 'applied index is now lower than readState.Index' (duration: 118.831296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:57.524701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.109345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:40:57.524987Z","caller":"traceutil/trace.go:171","msg":"trace[112971628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4931; }","duration":"233.43286ms","start":"2026-08-22T14:40:57.291479Z","end":"2026-08-22T14:40:57.524911Z","steps":["trace[112971628] 'agreement among raft nodes before linearized reading' (duration: 232.254561ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:00.727354Z","caller":"traceutil/trace.go:171","msg":"trace[1904986126] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"121.56392ms","start":"2026-08-22T14:41:00.605507Z","end":"2026-08-22T14:41:00.727071Z","steps":["trace[1904986126] 'process raft request' (duration: 121.239045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:04.103469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.973786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-22T14:41:04.103525Z","caller":"traceutil/trace.go:171","msg":"trace[1608848809] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"113.869426ms","start":"2026-08-22T14:41:03.989639Z","end":"2026-08-22T14:41:04.103508Z","steps":["trace[1608848809] 'process raft request' (duration: 113.534061ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:04.103567Z","caller":"traceutil/trace.go:171","msg":"trace[1004269205] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5034; }","duration":"117.101159ms","start":"2026-08-22T14:41:03.986443Z","end":"2026-08-22T14:41:04.103544Z","steps":["trace[1004269205] 'agreement among raft nodes before linearized reading' (duration: 116.908526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:14.9737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.348827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-08-22T14:41:14.973788Z","caller":"traceutil/trace.go:171","msg":"trace[1820168608] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5134; }","duration":"502.51021ms","start":"2026-08-22T14:41:14.471257Z","end":"2026-08-22T14:41:14.973767Z","steps":["trace[1820168608] 'range keys from in-memory index tree' (duration: 502.160274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:14.973822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:41:14.471231Z","time spent":"502.58475ms","remote":"127.0.0.1:45698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-22T14:41:14.974049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.218788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:41:14.97414Z","caller":"traceutil/trace.go:171","msg":"trace[1751957856] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5134; }","duration":"427.368551ms","start":"2026-08-22T14:41:14.546749Z","end":"2026-08-22T14:41:14.974118Z","steps":["trace[1751957856] 'count revisions from in-memory index tree' (duration: 427.047316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:14.974174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:41:14.54673Z","time spent":"427.436002ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:41:14.974306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.811189ms","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-22T14:41:14.974421Z","caller":"traceutil/trace.go:171","msg":"trace[100003790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5134; }","duration":"167.126293ms","start":"2026-08-22T14:41:14.807267Z","end":"2026-08-22T14:41:14.974393Z","steps":["trace[100003790] 'range keys from in-memory index tree' (duration: 166.293411ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:15.094994Z","caller":"traceutil/trace.go:171","msg":"trace[1174944134] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"114.453029ms","start":"2026-08-22T14:41:14.980507Z","end":"2026-08-22T14:41:15.09496Z","steps":["trace[1174944134] 'process raft request' (duration: 96.029989ms)","trace[1174944134] 'compare' (duration: 18.241447ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:15.096634Z","caller":"traceutil/trace.go:171","msg":"trace[1550252980] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"115.715678ms","start":"2026-08-22T14:41:14.980899Z","end":"2026-08-22T14:41:15.096614Z","steps":["trace[1550252980] 'process raft request' (duration: 114.013861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:16.07476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.477184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:41:16.075238Z","caller":"traceutil/trace.go:171","msg":"trace[1434994818] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"183.444526ms","start":"2026-08-22T14:41:15.891771Z","end":"2026-08-22T14:41:16.075215Z","steps":["trace[1434994818] 'process raft request' (duration: 60.366273ms)","trace[1434994818] 'compare' (duration: 122.350102ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:16.8598Z","caller":"traceutil/trace.go:171","msg":"trace[369733443] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5339; }","duration":"302.622515ms","start":"2026-08-22T14:41:16.557156Z","end":"2026-08-22T14:41:16.859779Z","steps":["trace[369733443] 'read index received' (duration: 302.403732ms)","trace[369733443] 'applied index is now lower than readState.Index' (duration: 216.963µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:16.859976Z","caller":"traceutil/trace.go:171","msg":"trace[454577165] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"382.622687ms","start":"2026-08-22T14:41:16.477296Z","end":"2026-08-22T14:41:16.859919Z","steps":["trace[454577165] 'process raft request' (duration: 382.324633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:16.860161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:41:16.477277Z","time spent":"382.78945ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:41:16.860285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.067612ms","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-22T14:41:16.860405Z","caller":"traceutil/trace.go:171","msg":"trace[2005196851] 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:5146; }","duration":"303.232954ms","start":"2026-08-22T14:41:16.557123Z","end":"2026-08-22T14:41:16.860356Z","steps":["trace[2005196851] 'agreement among raft nodes before linearized reading' (duration: 302.795037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:16.860457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:41:16.557105Z","time spent":"303.338786ms","remote":"127.0.0.1:45238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-22T14:41:44.601951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-08-22T14:41:44.687426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"83.636318ms","hash":3764952579,"current-db-size-bytes":52400128,"current-db-size":"52 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T14:41:44.687493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3764952579,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:41:55.937286Z","caller":"traceutil/trace.go:171","msg":"trace[1813999457] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"122.705863ms","start":"2026-08-22T14:41:55.814545Z","end":"2026-08-22T14:41:55.937251Z","steps":["trace[1813999457] 'process raft request' (duration: 90.011879ms)","trace[1813999457] 'compare' (duration: 31.751929ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:55.938008Z","caller":"traceutil/trace.go:171","msg":"trace[2130683478] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"119.8902ms","start":"2026-08-22T14:41:55.818105Z","end":"2026-08-22T14:41:55.937996Z","steps":["trace[2130683478] 'process raft request' (duration: 118.339376ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:55.939339Z","caller":"traceutil/trace.go:171","msg":"trace[1718447571] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"121.29223ms","start":"2026-08-22T14:41:55.818032Z","end":"2026-08-22T14:41:55.939324Z","steps":["trace[1718447571] 'process raft request' (duration: 118.372196ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:55.338058Z","caller":"traceutil/trace.go:171","msg":"trace[302691606] transaction","detail":"{read_only:false; response_revision:6726; number_of_response:1; }","duration":"135.264528ms","start":"2026-08-22T14:44:55.202772Z","end":"2026-08-22T14:44:55.338037Z","steps":["trace[302691606] 'process raft request' (duration: 135.115086ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:55.348967Z","caller":"traceutil/trace.go:171","msg":"trace[450672499] transaction","detail":"{read_only:false; response_revision:6727; number_of_response:1; }","duration":"145.241792ms","start":"2026-08-22T14:44:55.203695Z","end":"2026-08-22T14:44:55.348937Z","steps":["trace[450672499] 'process raft request' (duration: 145.063359ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:58.48643Z","caller":"traceutil/trace.go:171","msg":"trace[443346813] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"103.650044ms","start":"2026-08-22T14:44:58.382751Z","end":"2026-08-22T14:44:58.486401Z","steps":["trace[443346813] 'process raft request' (duration: 76.96785ms)","trace[443346813] 'compare' (duration: 26.564232ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:44:58.515625Z","caller":"traceutil/trace.go:171","msg":"trace[328212767] transaction","detail":"{read_only:false; response_revision:6745; number_of_response:1; }","duration":"132.842303ms","start":"2026-08-22T14:44:58.382767Z","end":"2026-08-22T14:44:58.51561Z","steps":["trace[328212767] 'process raft request' (duration: 132.737741ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:00.442094Z","caller":"traceutil/trace.go:171","msg":"trace[584655785] linearizableReadLoop","detail":"{readStateIndex:7001; appliedIndex:7000; }","duration":"149.706981ms","start":"2026-08-22T14:45:00.292357Z","end":"2026-08-22T14:45:00.442064Z","steps":["trace[584655785] 'read index received' (duration: 149.258394ms)","trace[584655785] 'applied index is now lower than readState.Index' (duration: 446.837µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:45:00.442305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.902824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:45:00.442352Z","caller":"traceutil/trace.go:171","msg":"trace[548070268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6753; }","duration":"150.040396ms","start":"2026-08-22T14:45:00.2923Z","end":"2026-08-22T14:45:00.44234Z","steps":["trace[548070268] 'agreement among raft nodes before linearized reading' (duration: 149.909494ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:45:00.442454Z","caller":"traceutil/trace.go:171","msg":"trace[341139910] transaction","detail":"{read_only:false; response_revision:6753; number_of_response:1; }","duration":"177.331198ms","start":"2026-08-22T14:45:00.265085Z","end":"2026-08-22T14:45:00.442416Z","steps":["trace[341139910] 'process raft request' (duration: 176.75402ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:46:44.609576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5320} {"level":"info","ts":"2026-08-22T14:46:44.683196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5320,"took":"72.070292ms","hash":489127797,"current-db-size-bytes":52400128,"current-db-size":"52 MB","current-db-size-in-use-bytes":18321408,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-22T14:46:44.683269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":489127797,"revision":5320,"compact-revision":2866}