{"level":"warn","ts":"2026-07-22T14:07:03.679733Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T14:07:03.680124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30: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.19.213.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30: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-07-22T14:07:03.680501Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T14:07:03.680565Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-07-22T14:07:03.680625Z","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-07-22T14:07:03.681834Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-07-22T14:07:03.68202Z","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.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30: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-07-22T14:07:03.687688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.112331ms"} {"level":"info","ts":"2026-07-22T14:07:03.696293Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-07-22T14:07:03.699642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T14:07:03.699744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-07-22T14:07:03.699772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T14:07:03.699799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-07-22T14:07:03.699882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-07-22T14:07:03.711916Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T14:07:03.713725Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T14:07:03.715019Z","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-07-22T14:07:03.71779Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T14:07:03.718053Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T14:07:03.718157Z","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-07-22T14:07:03.718348Z","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-07-22T14:07:03.718382Z","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-07-22T14:07:03.7199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-07-22T14:07:03.720265Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-07-22T14:07:03.725674Z","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-07-22T14:07:03.725851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-07-22T14:07:03.725967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-07-22T14:07:03.726776Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T14:07:03.726883Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T14:07:04.311623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T14:07:04.3117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T14:07:04.311743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-07-22T14:07:04.311765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-07-22T14:07:04.311775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-07-22T14:07:04.311789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-07-22T14:07:04.311801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-07-22T14:07:04.313283Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:07:04.314365Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T14:07:04.314368Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T14:07:04.314455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T14:07:04.314998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T14:07:04.314958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:07:04.315033Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T14:07:04.315228Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:07:04.315297Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:07:04.31757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T14:07:04.318244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-07-22T14:07:11.653726Z","caller":"traceutil/trace.go:171","msg":"trace[1288055173] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"114.254518ms","start":"2026-07-22T14:07:11.539452Z","end":"2026-07-22T14:07:11.653707Z","steps":["trace[1288055173] 'process raft request' (duration: 114.162896ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:17.331538Z","caller":"traceutil/trace.go:171","msg":"trace[1888748475] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"149.726533ms","start":"2026-07-22T14:07:17.181787Z","end":"2026-07-22T14:07:17.331513Z","steps":["trace[1888748475] 'process raft request' (duration: 149.553349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:17.895693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.686913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:07:17.895786Z","caller":"traceutil/trace.go:171","msg":"trace[1052468232] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"395.185704ms","start":"2026-07-22T14:07:17.500589Z","end":"2026-07-22T14:07:17.895774Z","steps":["trace[1052468232] 'read index received' (duration: 34.061µs)","trace[1052468232] 'applied index is now lower than readState.Index' (duration: 395.150803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:07:17.895883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.293597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-07-22T14:07:17.895854Z","caller":"traceutil/trace.go:171","msg":"trace[131391050] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"506.800599ms","start":"2026-07-22T14:07:17.389014Z","end":"2026-07-22T14:07:17.895815Z","steps":["trace[131391050] 'process raft request' (duration: 103.587034ms)","trace[131391050] 'compare' (duration: 402.578711ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:17.895902Z","caller":"traceutil/trace.go:171","msg":"trace[377064298] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:362; }","duration":"395.342228ms","start":"2026-07-22T14:07:17.500554Z","end":"2026-07-22T14:07:17.895896Z","steps":["trace[377064298] 'agreement among raft nodes before linearized reading' (duration: 395.293477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:17.895928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:17.500534Z","time spent":"395.387799ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-22T14:07:17.89602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:17.38899Z","time spent":"506.930702ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:07:17.896143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.472201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T14:07:17.896163Z","caller":"traceutil/trace.go:171","msg":"trace[1768199170] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:362; }","duration":"395.541483ms","start":"2026-07-22T14:07:17.500615Z","end":"2026-07-22T14:07:17.896157Z","steps":["trace[1768199170] 'agreement among raft nodes before linearized reading' (duration: 395.507902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:17.896198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:17.500588Z","time spent":"395.600344ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-07-22T14:07:18.458344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:17.897278Z","time spent":"560.797556ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-22T14:07:18.464246Z","caller":"traceutil/trace.go:171","msg":"trace[362738014] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:374; }","duration":"177.463074ms","start":"2026-07-22T14:07:18.286768Z","end":"2026-07-22T14:07:18.464231Z","steps":["trace[362738014] 'read index received' (duration: 146.105913ms)","trace[362738014] 'applied index is now lower than readState.Index' (duration: 31.356591ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:18.46433Z","caller":"traceutil/trace.go:171","msg":"trace[769057227] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"560.13992ms","start":"2026-07-22T14:07:17.904166Z","end":"2026-07-22T14:07:18.464306Z","steps":["trace[769057227] 'process raft request' (duration: 559.967696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:18.464371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.589246ms","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-07-22T14:07:18.464459Z","caller":"traceutil/trace.go:171","msg":"trace[70031011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"177.720509ms","start":"2026-07-22T14:07:18.286721Z","end":"2026-07-22T14:07:18.464442Z","steps":["trace[70031011] 'agreement among raft nodes before linearized reading' (duration: 177.583176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:18.464461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:17.904148Z","time spent":"560.242842ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:07:41.626934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.33252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:07:41.627217Z","caller":"traceutil/trace.go:171","msg":"trace[804592124] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"449.117023ms","start":"2026-07-22T14:07:41.178062Z","end":"2026-07-22T14:07:41.627179Z","steps":["trace[804592124] 'process raft request' (duration: 161.455646ms)","trace[804592124] 'compare' (duration: 287.214168ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:41.627263Z","caller":"traceutil/trace.go:171","msg":"trace[17892083] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"102.447821ms","start":"2026-07-22T14:07:41.524799Z","end":"2026-07-22T14:07:41.627247Z","steps":["trace[17892083] 'read index received' (duration: 100.190349ms)","trace[17892083] 'applied index is now lower than readState.Index' (duration: 2.256452ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:07:41.627347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.551383ms","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-07-22T14:07:41.627336Z","caller":"traceutil/trace.go:171","msg":"trace[1159005871] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"447.808599ms","start":"2026-07-22T14:07:41.179489Z","end":"2026-07-22T14:07:41.627298Z","steps":["trace[1159005871] 'process raft request' (duration: 447.547774ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:41.62738Z","caller":"traceutil/trace.go:171","msg":"trace[1390100294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"102.620754ms","start":"2026-07-22T14:07:41.524748Z","end":"2026-07-22T14:07:41.627369Z","steps":["trace[1390100294] 'agreement among raft nodes before linearized reading' (duration: 102.540443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:41.627396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:41.178041Z","time spent":"449.240866ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:07:41.627356Z","caller":"traceutil/trace.go:171","msg":"trace[80629270] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"320.294972ms","start":"2026-07-22T14:07:41.307006Z","end":"2026-07-22T14:07:41.627301Z","steps":["trace[80629270] 'process raft request' (duration: 320.18968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:41.627506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:41.179468Z","time spent":"447.923011ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:07:41.627624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:41.306962Z","time spent":"320.546317ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T14:07:48.427294Z","caller":"traceutil/trace.go:171","msg":"trace[487540525] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"374.736466ms","start":"2026-07-22T14:07:48.052533Z","end":"2026-07-22T14:07:48.427269Z","steps":["trace[487540525] 'read index received' (duration: 373.645496ms)","trace[487540525] 'applied index is now lower than readState.Index' (duration: 1.089939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:07:48.427676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.118672ms","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-07-22T14:07:48.427772Z","caller":"traceutil/trace.go:171","msg":"trace[590719630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"375.267235ms","start":"2026-07-22T14:07:48.052488Z","end":"2026-07-22T14:07:48.427756Z","steps":["trace[590719630] 'agreement among raft nodes before linearized reading' (duration: 374.98029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:48.427821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:48.052469Z","time spent":"375.343426ms","remote":"127.0.0.1:57420","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-07-22T14:07:48.427678Z","caller":"traceutil/trace.go:171","msg":"trace[852992141] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"409.238055ms","start":"2026-07-22T14:07:48.018107Z","end":"2026-07-22T14:07:48.427345Z","steps":["trace[852992141] 'process raft request' (duration: 408.39604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:48.42802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:48.018077Z","time spent":"409.843015ms","remote":"127.0.0.1:57232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:07:48.64898Z","caller":"traceutil/trace.go:171","msg":"trace[2047304758] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"214.334913ms","start":"2026-07-22T14:07:48.434614Z","end":"2026-07-22T14:07:48.648949Z","steps":["trace[2047304758] 'process raft request' (duration: 127.235975ms)","trace[2047304758] 'compare' (duration: 86.999826ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:48.747575Z","caller":"traceutil/trace.go:171","msg":"trace[783619098] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"118.688555ms","start":"2026-07-22T14:07:48.628861Z","end":"2026-07-22T14:07:48.74755Z","steps":["trace[783619098] 'read index received' (duration: 118.430231ms)","trace[783619098] 'applied index is now lower than readState.Index' (duration: 257.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:48.747854Z","caller":"traceutil/trace.go:171","msg":"trace[468735338] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"293.503401ms","start":"2026-07-22T14:07:48.45434Z","end":"2026-07-22T14:07:48.747843Z","steps":["trace[468735338] 'process raft request' (duration: 293.037562ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:48.748121Z","caller":"traceutil/trace.go:171","msg":"trace[973367731] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"251.714983ms","start":"2026-07-22T14:07:48.49636Z","end":"2026-07-22T14:07:48.748075Z","steps":["trace[973367731] 'process raft request' (duration: 251.148433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:48.748344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.476189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T14:07:48.748389Z","caller":"traceutil/trace.go:171","msg":"trace[1791515257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"119.566911ms","start":"2026-07-22T14:07:48.62881Z","end":"2026-07-22T14:07:48.748377Z","steps":["trace[1791515257] 'agreement among raft nodes before linearized reading' (duration: 119.435279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:48.748497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.700125ms","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-07-22T14:07:48.748542Z","caller":"traceutil/trace.go:171","msg":"trace[803652863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"114.773667ms","start":"2026-07-22T14:07:48.633757Z","end":"2026-07-22T14:07:48.748531Z","steps":["trace[803652863] 'agreement among raft nodes before linearized reading' (duration: 114.708376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:49.587781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.738341ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:07:49.587962Z","caller":"traceutil/trace.go:171","msg":"trace[1525680420] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"329.932974ms","start":"2026-07-22T14:07:49.25801Z","end":"2026-07-22T14:07:49.587943Z","steps":["trace[1525680420] 'range keys from in-memory index tree' (duration: 329.72195ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:49.619243Z","caller":"traceutil/trace.go:171","msg":"trace[118327941] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"824.355088ms","start":"2026-07-22T14:07:48.794869Z","end":"2026-07-22T14:07:49.619225Z","steps":["trace[118327941] 'process raft request' (duration: 824.261886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:49.619336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:48.794845Z","time spent":"824.45085ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:07:50.169621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338270148403796292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T14:07:50.507824Z","caller":"traceutil/trace.go:171","msg":"trace[635523119] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"885.262771ms","start":"2026-07-22T14:07:49.622517Z","end":"2026-07-22T14:07:50.507779Z","steps":["trace[635523119] 'process raft request' (duration: 865.168461ms)","trace[635523119] 'compare' (duration: 19.472939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:07:50.508377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.622495Z","time spent":"885.396853ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T14:07:50.514377Z","caller":"traceutil/trace.go:171","msg":"trace[2114007210] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:622; }","duration":"845.771667ms","start":"2026-07-22T14:07:49.668583Z","end":"2026-07-22T14:07:50.514354Z","steps":["trace[2114007210] 'read index received' (duration: 819.115333ms)","trace[2114007210] 'applied index is now lower than readState.Index' (duration: 26.655434ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:07:50.514503Z","caller":"traceutil/trace.go:171","msg":"trace[2025230616] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"889.948732ms","start":"2026-07-22T14:07:49.624545Z","end":"2026-07-22T14:07:50.514494Z","steps":["trace[2025230616] 'process raft request' (duration: 889.732218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:50.514605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.624539Z","time spent":"889.992222ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:07:50.514662Z","caller":"traceutil/trace.go:171","msg":"trace[1892952983] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"891.786854ms","start":"2026-07-22T14:07:49.622853Z","end":"2026-07-22T14:07:50.51464Z","steps":["trace[1892952983] 'process raft request' (duration: 891.259765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:50.514759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.622843Z","time spent":"891.869825ms","remote":"127.0.0.1:57202","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-07-22T14:07:50.514824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.566014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:07:50.514862Z","caller":"traceutil/trace.go:171","msg":"trace[515274258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"776.628895ms","start":"2026-07-22T14:07:49.738223Z","end":"2026-07-22T14:07:50.514852Z","steps":["trace[515274258] 'agreement among raft nodes before linearized reading' (duration: 776.573804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:50.514844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.242325ms","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-07-22T14:07:50.514898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.738207Z","time spent":"776.685085ms","remote":"127.0.0.1:57122","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-07-22T14:07:50.514929Z","caller":"traceutil/trace.go:171","msg":"trace[383088594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"846.384868ms","start":"2026-07-22T14:07:49.66852Z","end":"2026-07-22T14:07:50.514905Z","steps":["trace[383088594] 'agreement among raft nodes before linearized reading' (duration: 846.204995ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:50.514922Z","caller":"traceutil/trace.go:171","msg":"trace[2126732593] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"554.263827ms","start":"2026-07-22T14:07:49.960637Z","end":"2026-07-22T14:07:50.514901Z","steps":["trace[2126732593] 'process raft request' (duration: 553.682646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:50.51498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.668498Z","time spent":"846.473609ms","remote":"127.0.0.1:57420","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-07-22T14:07:50.515044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.960613Z","time spent":"554.370038ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:07:50.515226Z","caller":"traceutil/trace.go:171","msg":"trace[1338434396] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"891.535129ms","start":"2026-07-22T14:07:49.623129Z","end":"2026-07-22T14:07:50.514665Z","steps":["trace[1338434396] 'process raft request' (duration: 891.093692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:07:50.515301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:07:49.62312Z","time spent":"892.14307ms","remote":"127.0.0.1:57388","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-07-22T14:07:56.669235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.054518ms","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-07-22T14:07:56.669312Z","caller":"traceutil/trace.go:171","msg":"trace[525870806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"111.178041ms","start":"2026-07-22T14:07:56.558117Z","end":"2026-07-22T14:07:56.669295Z","steps":["trace[525870806] 'range keys from in-memory index tree' (duration: 110.836615ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:57.171458Z","caller":"traceutil/trace.go:171","msg":"trace[1076531680] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"100.918809ms","start":"2026-07-22T14:07:57.070514Z","end":"2026-07-22T14:07:57.171433Z","steps":["trace[1076531680] 'process raft request' (duration: 100.488811ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:07:57.340211Z","caller":"traceutil/trace.go:171","msg":"trace[2078211484] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"109.104224ms","start":"2026-07-22T14:07:57.231087Z","end":"2026-07-22T14:07:57.340192Z","steps":["trace[2078211484] 'process raft request' (duration: 105.229929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.079983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.584861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tt78\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-22T14:08:07.080049Z","caller":"traceutil/trace.go:171","msg":"trace[1355611339] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tt78; range_end:; response_count:1; response_revision:759; }","duration":"634.700783ms","start":"2026-07-22T14:08:06.445335Z","end":"2026-07-22T14:08:07.080036Z","steps":["trace[1355611339] 'range keys from in-memory index tree' (duration: 634.435489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.080078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:08:06.445292Z","time spent":"634.779064ms","remote":"127.0.0.1:57338","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-6tt78\" "} {"level":"info","ts":"2026-07-22T14:08:07.080253Z","caller":"traceutil/trace.go:171","msg":"trace[103898774] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"399.620963ms","start":"2026-07-22T14:08:06.680619Z","end":"2026-07-22T14:08:07.08024Z","steps":["trace[103898774] 'process raft request' (duration: 395.177903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.080353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:08:06.680593Z","time spent":"399.696675ms","remote":"127.0.0.1:57420","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-07-22T14:08:07.080522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.894283ms","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-07-22T14:08:07.080557Z","caller":"traceutil/trace.go:171","msg":"trace[1336239237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"367.957675ms","start":"2026-07-22T14:08:06.71259Z","end":"2026-07-22T14:08:07.080548Z","steps":["trace[1336239237] 'agreement among raft nodes before linearized reading' (duration: 367.848953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.080582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:08:06.712574Z","time spent":"368.000905ms","remote":"127.0.0.1:57420","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-07-22T14:08:07.080093Z","caller":"traceutil/trace.go:171","msg":"trace[1955327345] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"367.431286ms","start":"2026-07-22T14:08:06.712627Z","end":"2026-07-22T14:08:07.080058Z","steps":["trace[1955327345] 'read index received' (duration: 363.068298ms)","trace[1955327345] 'applied index is now lower than readState.Index' (duration: 4.361518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:08:07.080801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.410038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T14:08:07.080844Z","caller":"traceutil/trace.go:171","msg":"trace[2010016965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"343.493919ms","start":"2026-07-22T14:08:06.73734Z","end":"2026-07-22T14:08:07.080834Z","steps":["trace[2010016965] 'agreement among raft nodes before linearized reading' (duration: 343.381607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.080874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:08:06.73732Z","time spent":"343.544061ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T14:08:07.08095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.791278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:08:07.080972Z","caller":"traceutil/trace.go:171","msg":"trace[1117548971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"342.841479ms","start":"2026-07-22T14:08:06.738123Z","end":"2026-07-22T14:08:07.080964Z","steps":["trace[1117548971] 'agreement among raft nodes before linearized reading' (duration: 342.807989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.080992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:08:06.738109Z","time spent":"342.878199ms","remote":"127.0.0.1:57122","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-07-22T14:08:07.081181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.382014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T14:08:07.08129Z","caller":"traceutil/trace.go:171","msg":"trace[1364173502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"203.474015ms","start":"2026-07-22T14:08:06.877743Z","end":"2026-07-22T14:08:07.081217Z","steps":["trace[1364173502] 'agreement among raft nodes before linearized reading' (duration: 203.360463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:07.081762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.740105ms","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-07-22T14:08:07.081884Z","caller":"traceutil/trace.go:171","msg":"trace[956249865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"211.889158ms","start":"2026-07-22T14:08:06.869942Z","end":"2026-07-22T14:08:07.081831Z","steps":["trace[956249865] 'agreement among raft nodes before linearized reading' (duration: 210.905732ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:08:38.463221Z","caller":"traceutil/trace.go:171","msg":"trace[494071304] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"234.365137ms","start":"2026-07-22T14:08:38.228829Z","end":"2026-07-22T14:08:38.463194Z","steps":["trace[494071304] 'process raft request' (duration: 234.221315ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:08:59.804816Z","caller":"traceutil/trace.go:171","msg":"trace[369503800] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"119.566757ms","start":"2026-07-22T14:08:59.685222Z","end":"2026-07-22T14:08:59.804788Z","steps":["trace[369503800] 'read index received' (duration: 119.551866ms)","trace[369503800] 'applied index is now lower than readState.Index' (duration: 10.331µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:08:59.804845Z","caller":"traceutil/trace.go:171","msg":"trace[148901253] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"262.767658ms","start":"2026-07-22T14:08:59.542053Z","end":"2026-07-22T14:08:59.804821Z","steps":["trace[148901253] 'process raft request' (duration: 262.585816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:08:59.805101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.86679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-ddqvb\" ","response":"range_response_count:1 size:4178"} {"level":"info","ts":"2026-07-22T14:08:59.805144Z","caller":"traceutil/trace.go:171","msg":"trace[1107803978] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-ddqvb; range_end:; response_count:1; response_revision:1421; }","duration":"119.946432ms","start":"2026-07-22T14:08:59.685185Z","end":"2026-07-22T14:08:59.805131Z","steps":["trace[1107803978] 'agreement among raft nodes before linearized reading' (duration: 119.749529ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:00.986357Z","caller":"traceutil/trace.go:171","msg":"trace[740091335] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"148.839955ms","start":"2026-07-22T14:09:00.837497Z","end":"2026-07-22T14:09:00.986337Z","steps":["trace[740091335] 'process raft request' (duration: 148.704393ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:03.503663Z","caller":"traceutil/trace.go:171","msg":"trace[1247695805] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"119.71014ms","start":"2026-07-22T14:09:03.383928Z","end":"2026-07-22T14:09:03.503638Z","steps":["trace[1247695805] 'process raft request' (duration: 119.643479ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:03.503833Z","caller":"traceutil/trace.go:171","msg":"trace[1468001017] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"120.765303ms","start":"2026-07-22T14:09:03.383041Z","end":"2026-07-22T14:09:03.503807Z","steps":["trace[1468001017] 'process raft request' (duration: 52.623306ms)","trace[1468001017] 'compare' (duration: 67.657561ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:09:03.503933Z","caller":"traceutil/trace.go:171","msg":"trace[1029937251] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"120.459599ms","start":"2026-07-22T14:09:03.383463Z","end":"2026-07-22T14:09:03.503922Z","steps":["trace[1029937251] 'process raft request' (duration: 120.052054ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:25.226399Z","caller":"traceutil/trace.go:171","msg":"trace[2046087788] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"487.424474ms","start":"2026-07-22T14:09:24.73894Z","end":"2026-07-22T14:09:25.226364Z","steps":["trace[2046087788] 'read index received' (duration: 486.593904ms)","trace[2046087788] 'applied index is now lower than readState.Index' (duration: 829.5µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:09:25.226524Z","caller":"traceutil/trace.go:171","msg":"trace[1288580002] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"518.883918ms","start":"2026-07-22T14:09:24.707619Z","end":"2026-07-22T14:09:25.226503Z","steps":["trace[1288580002] 'process raft request' (duration: 518.516624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:25.2267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.834264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92930eae-9187-4b39-9dc3-c708fd7c792c\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-22T14:09:25.226758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.635466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:09:25.22683Z","caller":"traceutil/trace.go:171","msg":"trace[38281197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"487.929109ms","start":"2026-07-22T14:09:24.738891Z","end":"2026-07-22T14:09:25.22682Z","steps":["trace[38281197] 'agreement among raft nodes before linearized reading' (duration: 487.621555ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:25.226829Z","caller":"traceutil/trace.go:171","msg":"trace[1742328193] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92930eae-9187-4b39-9dc3-c708fd7c792c; range_end:; response_count:1; response_revision:1827; }","duration":"423.005577ms","start":"2026-07-22T14:09:24.803804Z","end":"2026-07-22T14:09:25.22681Z","steps":["trace[1742328193] 'agreement among raft nodes before linearized reading' (duration: 422.737514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:25.22679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:24.707591Z","time spent":"519.037141ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:09:25.226875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:24.803766Z","time spent":"423.095047ms","remote":"127.0.0.1:57338","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-92930eae-9187-4b39-9dc3-c708fd7c792c\" "} {"level":"warn","ts":"2026-07-22T14:09:25.226907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:24.738873Z","time spent":"488.026141ms","remote":"127.0.0.1:57122","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-07-22T14:09:25.419434Z","caller":"traceutil/trace.go:171","msg":"trace[270752387] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"185.357697ms","start":"2026-07-22T14:09:25.234023Z","end":"2026-07-22T14:09:25.419381Z","steps":["trace[270752387] 'process raft request' (duration: 169.937989ms)","trace[270752387] 'compare' (duration: 15.296007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:09:26.009218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.127863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:09:26.009404Z","caller":"traceutil/trace.go:171","msg":"trace[544416395] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"410.251974ms","start":"2026-07-22T14:09:25.599058Z","end":"2026-07-22T14:09:26.00931Z","steps":["trace[544416395] 'read index received' (duration: 74.995968ms)","trace[544416395] 'applied index is now lower than readState.Index' (duration: 335.254926ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:09:26.009584Z","caller":"traceutil/trace.go:171","msg":"trace[1652910925] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"451.076327ms","start":"2026-07-22T14:09:25.558467Z","end":"2026-07-22T14:09:26.009543Z","steps":["trace[1652910925] 'process raft request' (duration: 115.520759ms)","trace[1652910925] 'compare' (duration: 334.219713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:09:26.009745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.606708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92930eae-9187-4b39-9dc3-c708fd7c792c\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-07-22T14:09:26.009829Z","caller":"traceutil/trace.go:171","msg":"trace[1265020997] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92930eae-9187-4b39-9dc3-c708fd7c792c; range_end:; response_count:1; response_revision:1830; }","duration":"410.79986ms","start":"2026-07-22T14:09:25.599008Z","end":"2026-07-22T14:09:26.009808Z","steps":["trace[1265020997] 'agreement among raft nodes before linearized reading' (duration: 410.523877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:26.009881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.598983Z","time spent":"410.887831ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92930eae-9187-4b39-9dc3-c708fd7c792c\" "} {"level":"warn","ts":"2026-07-22T14:09:26.009886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.558442Z","time spent":"451.232039ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:09:26.019838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.475434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-22T14:09:26.019853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.163747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T14:09:26.019885Z","caller":"traceutil/trace.go:171","msg":"trace[210444088] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1831; }","duration":"415.586106ms","start":"2026-07-22T14:09:25.604287Z","end":"2026-07-22T14:09:26.019873Z","steps":["trace[210444088] 'agreement among raft nodes before linearized reading' (duration: 415.410944ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:26.019908Z","caller":"traceutil/trace.go:171","msg":"trace[1684534524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1831; }","duration":"407.241989ms","start":"2026-07-22T14:09:25.612654Z","end":"2026-07-22T14:09:26.019896Z","steps":["trace[1684534524] 'agreement among raft nodes before linearized reading' (duration: 407.072777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:26.019921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.604261Z","time spent":"415.647046ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T14:09:26.019936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.612464Z","time spent":"407.465891ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:09:26.019918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.861159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T14:09:26.019977Z","caller":"traceutil/trace.go:171","msg":"trace[234707141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1831; }","duration":"409.93417ms","start":"2026-07-22T14:09:25.610029Z","end":"2026-07-22T14:09:26.019963Z","steps":["trace[234707141] 'agreement among raft nodes before linearized reading' (duration: 409.740177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:26.019984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.19501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-22T14:09:26.019992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.417389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-22T14:09:26.020021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.610003Z","time spent":"410.01157ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T14:09:26.020049Z","caller":"traceutil/trace.go:171","msg":"trace[861136371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1831; }","duration":"414.28676ms","start":"2026-07-22T14:09:25.605744Z","end":"2026-07-22T14:09:26.02003Z","steps":["trace[861136371] 'agreement among raft nodes before linearized reading' (duration: 414.081638ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:26.020053Z","caller":"traceutil/trace.go:171","msg":"trace[891269220] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"368.541301ms","start":"2026-07-22T14:09:25.651495Z","end":"2026-07-22T14:09:26.020037Z","steps":["trace[891269220] 'agreement among raft nodes before linearized reading' (duration: 368.405119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:09:26.020107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.60573Z","time spent":"414.364611ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T14:09:26.02012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:09:25.651468Z","time spent":"368.635431ms","remote":"127.0.0.1:60474","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":"info","ts":"2026-07-22T14:09:34.024036Z","caller":"traceutil/trace.go:171","msg":"trace[949842296] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"141.749643ms","start":"2026-07-22T14:09:33.882265Z","end":"2026-07-22T14:09:34.024015Z","steps":["trace[949842296] 'process raft request' (duration: 141.551241ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:35.708229Z","caller":"traceutil/trace.go:171","msg":"trace[947445151] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"103.279051ms","start":"2026-07-22T14:09:35.604926Z","end":"2026-07-22T14:09:35.708205Z","steps":["trace[947445151] 'process raft request' (duration: 103.127749ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:09:42.93889Z","caller":"traceutil/trace.go:171","msg":"trace[1828280429] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"102.162403ms","start":"2026-07-22T14:09:42.836705Z","end":"2026-07-22T14:09:42.938868Z","steps":["trace[1828280429] 'process raft request' (duration: 101.994281ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:00.319122Z","caller":"traceutil/trace.go:171","msg":"trace[1365242409] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"173.088237ms","start":"2026-07-22T14:10:00.146015Z","end":"2026-07-22T14:10:00.319103Z","steps":["trace[1365242409] 'read index received' (duration: 173.018515ms)","trace[1365242409] 'applied index is now lower than readState.Index' (duration: 68.922µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:10:00.319328Z","caller":"traceutil/trace.go:171","msg":"trace[1468009735] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"264.009423ms","start":"2026-07-22T14:10:00.055303Z","end":"2026-07-22T14:10:00.319313Z","steps":["trace[1468009735] 'process raft request' (duration: 263.656824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.319555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.517067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:10:00.319622Z","caller":"traceutil/trace.go:171","msg":"trace[830177596] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2074; }","duration":"173.63577ms","start":"2026-07-22T14:10:00.145969Z","end":"2026-07-22T14:10:00.319605Z","steps":["trace[830177596] 'agreement among raft nodes before linearized reading' (duration: 173.515427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.894218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.623386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:10:00.894667Z","caller":"traceutil/trace.go:171","msg":"trace[2092926948] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"575.367267ms","start":"2026-07-22T14:10:00.319287Z","end":"2026-07-22T14:10:00.894654Z","steps":["trace[2092926948] 'read index received' (duration: 428.253188ms)","trace[2092926948] 'applied index is now lower than readState.Index' (duration: 147.113329ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:10:00.894858Z","caller":"traceutil/trace.go:171","msg":"trace[1502201782] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"666.179132ms","start":"2026-07-22T14:10:00.228651Z","end":"2026-07-22T14:10:00.89483Z","steps":["trace[1502201782] 'process raft request' (duration: 665.874284ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:00.894891Z","caller":"traceutil/trace.go:171","msg":"trace[1120938723] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"722.173638ms","start":"2026-07-22T14:10:00.172688Z","end":"2026-07-22T14:10:00.894861Z","steps":["trace[1120938723] 'process raft request' (duration: 574.834204ms)","trace[1120938723] 'compare' (duration: 146.35457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:10:00.894933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.543334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T14:10:00.89499Z","caller":"traceutil/trace.go:171","msg":"trace[402229213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2076; }","duration":"600.642587ms","start":"2026-07-22T14:10:00.294332Z","end":"2026-07-22T14:10:00.894975Z","steps":["trace[402229213] 'agreement among raft nodes before linearized reading' (duration: 600.461013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.895005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.228633Z","time spent":"666.295865ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:10:00.895034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.294321Z","time spent":"600.704218ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T14:10:00.895052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.172666Z","time spent":"722.314411ms","remote":"127.0.0.1:57420","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-07-22T14:10:00.8951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.121194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-22T14:10:00.895193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.864554ms","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-07-22T14:10:00.895193Z","caller":"traceutil/trace.go:171","msg":"trace[498836575] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2076; }","duration":"598.249358ms","start":"2026-07-22T14:10:00.29692Z","end":"2026-07-22T14:10:00.895169Z","steps":["trace[498836575] 'agreement among raft nodes before linearized reading' (duration: 597.995592ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:00.895233Z","caller":"traceutil/trace.go:171","msg":"trace[1192409554] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2076; }","duration":"655.899215ms","start":"2026-07-22T14:10:00.239321Z","end":"2026-07-22T14:10:00.89522Z","steps":["trace[1192409554] 'agreement among raft nodes before linearized reading' (duration: 655.808223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.895246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.296886Z","time spent":"598.35226ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T14:10:00.895267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.239272Z","time spent":"655.988157ms","remote":"127.0.0.1:57420","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-07-22T14:10:00.895271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.917818ms","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-07-22T14:10:00.895342Z","caller":"traceutil/trace.go:171","msg":"trace[1881839726] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2076; }","duration":"605.998081ms","start":"2026-07-22T14:10:00.289329Z","end":"2026-07-22T14:10:00.895327Z","steps":["trace[1881839726] 'agreement among raft nodes before linearized reading' (duration: 605.857357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.89538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.289318Z","time spent":"606.053812ms","remote":"127.0.0.1:60474","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-07-22T14:10:00.895469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.670827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-22T14:10:00.895462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.754119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:10:00.895504Z","caller":"traceutil/trace.go:171","msg":"trace[1564528719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2076; }","duration":"620.742299ms","start":"2026-07-22T14:10:00.274753Z","end":"2026-07-22T14:10:00.895495Z","steps":["trace[1564528719] 'agreement among raft nodes before linearized reading' (duration: 620.624896ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:00.895516Z","caller":"traceutil/trace.go:171","msg":"trace[1321039071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2076; }","duration":"158.854801ms","start":"2026-07-22T14:10:00.73665Z","end":"2026-07-22T14:10:00.895504Z","steps":["trace[1321039071] 'agreement among raft nodes before linearized reading' (duration: 158.738298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.895537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.274733Z","time spent":"620.79801ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T14:10:00.895157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.776381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T14:10:00.895661Z","caller":"traceutil/trace.go:171","msg":"trace[1706486482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2076; }","duration":"601.5443ms","start":"2026-07-22T14:10:00.294106Z","end":"2026-07-22T14:10:00.895651Z","steps":["trace[1706486482] 'agreement among raft nodes before linearized reading' (duration: 600.718649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:00.895743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:00.294093Z","time spent":"601.640282ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T14:10:01.035999Z","caller":"traceutil/trace.go:171","msg":"trace[1059921182] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"136.255663ms","start":"2026-07-22T14:10:00.899712Z","end":"2026-07-22T14:10:01.035968Z","steps":["trace[1059921182] 'process raft request' (duration: 94.214528ms)","trace[1059921182] 'compare' (duration: 41.877361ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:10:01.099507Z","caller":"traceutil/trace.go:171","msg":"trace[249845595] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"199.286098ms","start":"2026-07-22T14:10:00.900196Z","end":"2026-07-22T14:10:01.099482Z","steps":["trace[249845595] 'process raft request' (duration: 199.027812ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:01.099947Z","caller":"traceutil/trace.go:171","msg":"trace[344158751] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"197.609786ms","start":"2026-07-22T14:10:00.902324Z","end":"2026-07-22T14:10:01.099934Z","steps":["trace[344158751] 'process raft request' (duration: 197.513054ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:01.099979Z","caller":"traceutil/trace.go:171","msg":"trace[563113402] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"199.284808ms","start":"2026-07-22T14:10:00.900638Z","end":"2026-07-22T14:10:01.099923Z","steps":["trace[563113402] 'process raft request' (duration: 199.118154ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:10:01.100008Z","caller":"traceutil/trace.go:171","msg":"trace[1795477170] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"199.594375ms","start":"2026-07-22T14:10:00.900385Z","end":"2026-07-22T14:10:01.099979Z","steps":["trace[1795477170] 'process raft request' (duration: 199.011971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:10:01.627502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.659423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:10:01.627663Z","caller":"traceutil/trace.go:171","msg":"trace[1557567007] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"522.495643ms","start":"2026-07-22T14:10:01.105132Z","end":"2026-07-22T14:10:01.627627Z","steps":["trace[1557567007] 'process raft request' (duration: 178.343088ms)","trace[1557567007] 'compare' (duration: 343.53145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:10:01.627822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:10:01.105112Z","time spent":"522.621766ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:11:04.077815Z","caller":"traceutil/trace.go:171","msg":"trace[1772138765] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"137.461807ms","start":"2026-07-22T14:11:03.940327Z","end":"2026-07-22T14:11:04.077789Z","steps":["trace[1772138765] 'process raft request' (duration: 137.150951ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:11:36.477017Z","caller":"traceutil/trace.go:171","msg":"trace[2014741640] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"165.0279ms","start":"2026-07-22T14:11:36.311961Z","end":"2026-07-22T14:11:36.476989Z","steps":["trace[2014741640] 'process raft request' (duration: 156.969236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:11:37.288889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.66142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:11:37.288985Z","caller":"traceutil/trace.go:171","msg":"trace[1593911787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2697; }","duration":"602.829093ms","start":"2026-07-22T14:11:36.686134Z","end":"2026-07-22T14:11:37.288963Z","steps":["trace[1593911787] 'count revisions from in-memory index tree' (duration: 602.541088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:11:37.289052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:11:36.68611Z","time spent":"602.925785ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:11:37.289366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.319064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:11:37.289461Z","caller":"traceutil/trace.go:171","msg":"trace[999819765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2697; }","duration":"550.402016ms","start":"2026-07-22T14:11:36.738994Z","end":"2026-07-22T14:11:37.289396Z","steps":["trace[999819765] 'range keys from in-memory index tree' (duration: 550.188862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:11:37.289499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:11:36.738969Z","time spent":"550.521848ms","remote":"127.0.0.1:57122","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-07-22T14:11:37.289969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.199757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c4a200e65e362b\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-22T14:11:37.29001Z","caller":"traceutil/trace.go:171","msg":"trace[1507537991] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c4a200e65e362b; range_end:; response_count:1; response_revision:2697; }","duration":"192.285448ms","start":"2026-07-22T14:11:37.097714Z","end":"2026-07-22T14:11:37.29Z","steps":["trace[1507537991] 'range keys from in-memory index tree' (duration: 192.032064ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:11:37.436691Z","caller":"traceutil/trace.go:171","msg":"trace[521148727] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2801; }","duration":"145.638306ms","start":"2026-07-22T14:11:37.291027Z","end":"2026-07-22T14:11:37.436666Z","steps":["trace[521148727] 'read index received' (duration: 72.685776ms)","trace[521148727] 'applied index is now lower than readState.Index' (duration: 72.95143ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:11:37.436825Z","caller":"traceutil/trace.go:171","msg":"trace[1083404183] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"144.244752ms","start":"2026-07-22T14:11:37.292545Z","end":"2026-07-22T14:11:37.43679Z","steps":["trace[1083404183] 'process raft request' (duration: 144.055988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:11:37.436938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.891782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:11:37.436981Z","caller":"traceutil/trace.go:171","msg":"trace[476759639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2700; }","duration":"145.960553ms","start":"2026-07-22T14:11:37.29101Z","end":"2026-07-22T14:11:37.436971Z","steps":["trace[476759639] 'agreement among raft nodes before linearized reading' (duration: 145.80405ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:11:37.437452Z","caller":"traceutil/trace.go:171","msg":"trace[1312486229] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"168.2945ms","start":"2026-07-22T14:11:37.268436Z","end":"2026-07-22T14:11:37.43673Z","steps":["trace[1312486229] 'process raft request' (duration: 163.737539ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:11:42.638316Z","caller":"traceutil/trace.go:171","msg":"trace[2127753505] linearizableReadLoop","detail":"{readStateIndex:2832; appliedIndex:2831; }","duration":"131.521574ms","start":"2026-07-22T14:11:42.506766Z","end":"2026-07-22T14:11:42.638288Z","steps":["trace[2127753505] 'read index received' (duration: 131.28753ms)","trace[2127753505] 'applied index is now lower than readState.Index' (duration: 233.164µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:11:42.63851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.703167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:11:42.638625Z","caller":"traceutil/trace.go:171","msg":"trace[1155672517] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2727; }","duration":"131.89485ms","start":"2026-07-22T14:11:42.506704Z","end":"2026-07-22T14:11:42.638599Z","steps":["trace[1155672517] 'agreement among raft nodes before linearized reading' (duration: 131.683707ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:11:42.639245Z","caller":"traceutil/trace.go:171","msg":"trace[1738362949] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"132.675173ms","start":"2026-07-22T14:11:42.506544Z","end":"2026-07-22T14:11:42.639219Z","steps":["trace[1738362949] 'process raft request' (duration: 131.590024ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:12:06.880341Z","caller":"traceutil/trace.go:171","msg":"trace[1075019514] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"100.163839ms","start":"2026-07-22T14:12:06.78014Z","end":"2026-07-22T14:12:06.880303Z","steps":["trace[1075019514] 'process raft request' (duration: 100.000646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:12:10.003388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.363492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:12:10.00372Z","caller":"traceutil/trace.go:171","msg":"trace[53857668] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"113.892913ms","start":"2026-07-22T14:12:09.889787Z","end":"2026-07-22T14:12:10.00368Z","steps":["trace[53857668] 'compare' (duration: 108.190499ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:12:25.474413Z","caller":"traceutil/trace.go:171","msg":"trace[868229590] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"435.713826ms","start":"2026-07-22T14:12:25.03868Z","end":"2026-07-22T14:12:25.474393Z","steps":["trace[868229590] 'process raft request' (duration: 435.584524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:12:25.474551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:12:25.038666Z","time spent":"435.818888ms","remote":"127.0.0.1:57420","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-07-22T14:12:49.933685Z","caller":"traceutil/trace.go:171","msg":"trace[1381717006] transaction","detail":"{read_only:false; response_revision:3079; number_of_response:1; }","duration":"181.889443ms","start":"2026-07-22T14:12:49.751714Z","end":"2026-07-22T14:12:49.933603Z","steps":["trace[1381717006] 'process raft request' (duration: 85.20247ms)","trace[1381717006] 'compare' (duration: 96.372548ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:12:49.933695Z","caller":"traceutil/trace.go:171","msg":"trace[750555910] transaction","detail":"{read_only:false; response_revision:3080; number_of_response:1; }","duration":"117.092563ms","start":"2026-07-22T14:12:49.816563Z","end":"2026-07-22T14:12:49.933656Z","steps":["trace[750555910] 'process raft request' (duration: 116.976231ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:12:49.934012Z","caller":"traceutil/trace.go:171","msg":"trace[2093145324] linearizableReadLoop","detail":"{readStateIndex:3199; appliedIndex:3198; }","duration":"130.654957ms","start":"2026-07-22T14:12:49.803319Z","end":"2026-07-22T14:12:49.933974Z","steps":["trace[2093145324] 'read index received' (duration: 33.653249ms)","trace[2093145324] 'applied index is now lower than readState.Index' (duration: 96.997378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:12:49.934318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.970412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-22T14:12:49.934363Z","caller":"traceutil/trace.go:171","msg":"trace[1589652562] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3080; }","duration":"131.078484ms","start":"2026-07-22T14:12:49.803273Z","end":"2026-07-22T14:12:49.934352Z","steps":["trace[1589652562] 'agreement among raft nodes before linearized reading' (duration: 130.82792ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:12:50.547294Z","caller":"traceutil/trace.go:171","msg":"trace[1639290411] linearizableReadLoop","detail":"{readStateIndex:3204; appliedIndex:3202; }","duration":"173.916688ms","start":"2026-07-22T14:12:50.373348Z","end":"2026-07-22T14:12:50.547265Z","steps":["trace[1639290411] 'read index received' (duration: 16.079163ms)","trace[1639290411] 'applied index is now lower than readState.Index' (duration: 157.836385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:12:50.547635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.153581ms","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-07-22T14:12:50.547683Z","caller":"traceutil/trace.go:171","msg":"trace[202469387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3084; }","duration":"174.373475ms","start":"2026-07-22T14:12:50.373296Z","end":"2026-07-22T14:12:50.547669Z","steps":["trace[202469387] 'agreement among raft nodes before linearized reading' (duration: 174.086871ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:12:50.547642Z","caller":"traceutil/trace.go:171","msg":"trace[579598393] transaction","detail":"{read_only:false; response_revision:3084; number_of_response:1; }","duration":"221.770191ms","start":"2026-07-22T14:12:50.325819Z","end":"2026-07-22T14:12:50.54759Z","steps":["trace[579598393] 'process raft request' (duration: 155.409148ms)","trace[579598393] 'compare' (duration: 65.880576ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:02.80323Z","caller":"traceutil/trace.go:171","msg":"trace[576179118] linearizableReadLoop","detail":"{readStateIndex:3270; appliedIndex:3269; }","duration":"111.196405ms","start":"2026-07-22T14:13:02.692002Z","end":"2026-07-22T14:13:02.803198Z","steps":["trace[576179118] 'read index received' (duration: 12.19134ms)","trace[576179118] 'applied index is now lower than readState.Index' (duration: 99.003845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:13:02.803608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.576161ms","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-07-22T14:13:02.803735Z","caller":"traceutil/trace.go:171","msg":"trace[1854645413] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3147; }","duration":"111.726483ms","start":"2026-07-22T14:13:02.691956Z","end":"2026-07-22T14:13:02.803683Z","steps":["trace[1854645413] 'agreement among raft nodes before linearized reading' (duration: 111.344178ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:13:04.778193Z","caller":"traceutil/trace.go:171","msg":"trace[987894999] linearizableReadLoop","detail":"{readStateIndex:3279; appliedIndex:3278; }","duration":"128.597007ms","start":"2026-07-22T14:13:04.649562Z","end":"2026-07-22T14:13:04.778158Z","steps":["trace[987894999] 'read index received' (duration: 128.377253ms)","trace[987894999] 'applied index is now lower than readState.Index' (duration: 218.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:04.778327Z","caller":"traceutil/trace.go:171","msg":"trace[960587878] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"281.179396ms","start":"2026-07-22T14:13:04.497104Z","end":"2026-07-22T14:13:04.778283Z","steps":["trace[960587878] 'process raft request' (duration: 280.895182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:13:04.778472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.82736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T14:13:04.778549Z","caller":"traceutil/trace.go:171","msg":"trace[1391389313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3156; }","duration":"128.985702ms","start":"2026-07-22T14:13:04.64952Z","end":"2026-07-22T14:13:04.778506Z","steps":["trace[1391389313] 'agreement among raft nodes before linearized reading' (duration: 128.763198ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:13:11.038513Z","caller":"traceutil/trace.go:171","msg":"trace[1708934726] transaction","detail":"{read_only:false; response_revision:3188; number_of_response:1; }","duration":"143.271831ms","start":"2026-07-22T14:13:10.895213Z","end":"2026-07-22T14:13:11.038484Z","steps":["trace[1708934726] 'process raft request' (duration: 86.121977ms)","trace[1708934726] 'compare' (duration: 57.055042ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:11.926899Z","caller":"traceutil/trace.go:171","msg":"trace[579851165] linearizableReadLoop","detail":"{readStateIndex:3314; appliedIndex:3313; }","duration":"117.538874ms","start":"2026-07-22T14:13:11.809331Z","end":"2026-07-22T14:13:11.92687Z","steps":["trace[579851165] 'read index received' (duration: 117.392542ms)","trace[579851165] 'applied index is now lower than readState.Index' (duration: 145.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:11.926953Z","caller":"traceutil/trace.go:171","msg":"trace[386615352] transaction","detail":"{read_only:false; response_revision:3190; number_of_response:1; }","duration":"120.269844ms","start":"2026-07-22T14:13:11.806644Z","end":"2026-07-22T14:13:11.926913Z","steps":["trace[386615352] 'process raft request' (duration: 120.071131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:13:11.927118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.762467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7373"} {"level":"info","ts":"2026-07-22T14:13:11.927195Z","caller":"traceutil/trace.go:171","msg":"trace[1632012758] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3190; }","duration":"117.870259ms","start":"2026-07-22T14:13:11.809312Z","end":"2026-07-22T14:13:11.927182Z","steps":["trace[1632012758] 'agreement among raft nodes before linearized reading' (duration: 117.681376ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:13:25.040954Z","caller":"traceutil/trace.go:171","msg":"trace[544059029] linearizableReadLoop","detail":"{readStateIndex:3384; appliedIndex:3383; }","duration":"113.418469ms","start":"2026-07-22T14:13:24.927508Z","end":"2026-07-22T14:13:25.040926Z","steps":["trace[544059029] 'read index received' (duration: 113.256577ms)","trace[544059029] 'applied index is now lower than readState.Index' (duration: 160.882µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:25.041055Z","caller":"traceutil/trace.go:171","msg":"trace[56814499] transaction","detail":"{read_only:false; response_revision:3257; number_of_response:1; }","duration":"124.217583ms","start":"2026-07-22T14:13:24.916801Z","end":"2026-07-22T14:13:25.041019Z","steps":["trace[56814499] 'process raft request' (duration: 123.97351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:13:25.041181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.655102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T14:13:25.041232Z","caller":"traceutil/trace.go:171","msg":"trace[33052753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3257; }","duration":"113.725133ms","start":"2026-07-22T14:13:24.927494Z","end":"2026-07-22T14:13:25.041219Z","steps":["trace[33052753] 'agreement among raft nodes before linearized reading' (duration: 113.53765ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:13:31.338473Z","caller":"traceutil/trace.go:171","msg":"trace[152453108] linearizableReadLoop","detail":"{readStateIndex:3418; appliedIndex:3417; }","duration":"105.464902ms","start":"2026-07-22T14:13:31.232983Z","end":"2026-07-22T14:13:31.338448Z","steps":["trace[152453108] 'read index received' (duration: 105.230928ms)","trace[152453108] 'applied index is now lower than readState.Index' (duration: 232.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:31.33855Z","caller":"traceutil/trace.go:171","msg":"trace[435755556] transaction","detail":"{read_only:false; response_revision:3290; number_of_response:1; }","duration":"121.559ms","start":"2026-07-22T14:13:31.216949Z","end":"2026-07-22T14:13:31.338508Z","steps":["trace[435755556] 'process raft request' (duration: 121.334576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:13:31.338779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.617824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:13:31.338837Z","caller":"traceutil/trace.go:171","msg":"trace[824475758] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3290; }","duration":"105.841447ms","start":"2026-07-22T14:13:31.232977Z","end":"2026-07-22T14:13:31.338819Z","steps":["trace[824475758] 'agreement among raft nodes before linearized reading' (duration: 105.589773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:13:32.561149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.567353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:13:32.561275Z","caller":"traceutil/trace.go:171","msg":"trace[378361973] linearizableReadLoop","detail":"{readStateIndex:3422; appliedIndex:3421; }","duration":"289.523769ms","start":"2026-07-22T14:13:32.271731Z","end":"2026-07-22T14:13:32.561255Z","steps":["trace[378361973] 'read index received' (duration: 107.656272ms)","trace[378361973] 'applied index is now lower than readState.Index' (duration: 181.866187ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:13:32.561365Z","caller":"traceutil/trace.go:171","msg":"trace[1491951452] transaction","detail":"{read_only:false; response_revision:3294; number_of_response:1; }","duration":"334.777768ms","start":"2026-07-22T14:13:32.226575Z","end":"2026-07-22T14:13:32.561353Z","steps":["trace[1491951452] 'process raft request' (duration: 152.89661ms)","trace[1491951452] 'compare' (duration: 181.30208ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:13:32.561422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:13:32.22655Z","time spent":"334.840289ms","remote":"127.0.0.1:57420","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-07-22T14:13:32.561697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.967846ms","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-07-22T14:13:32.561732Z","caller":"traceutil/trace.go:171","msg":"trace[284378275] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3294; }","duration":"290.040106ms","start":"2026-07-22T14:13:32.271677Z","end":"2026-07-22T14:13:32.561717Z","steps":["trace[284378275] 'agreement among raft nodes before linearized reading' (duration: 289.878014ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:13:32.715254Z","caller":"traceutil/trace.go:171","msg":"trace[507660595] transaction","detail":"{read_only:false; response_revision:3296; number_of_response:1; }","duration":"149.605344ms","start":"2026-07-22T14:13:32.565605Z","end":"2026-07-22T14:13:32.71521Z","steps":["trace[507660595] 'process raft request' (duration: 138.871842ms)","trace[507660595] 'compare' (duration: 10.526489ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:14:19.504181Z","caller":"traceutil/trace.go:171","msg":"trace[828288236] transaction","detail":"{read_only:false; response_revision:3630; number_of_response:1; }","duration":"109.268558ms","start":"2026-07-22T14:14:19.394886Z","end":"2026-07-22T14:14:19.504155Z","steps":["trace[828288236] 'process raft request' (duration: 109.114456ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:19.505685Z","caller":"traceutil/trace.go:171","msg":"trace[220938181] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"108.925184ms","start":"2026-07-22T14:14:19.396732Z","end":"2026-07-22T14:14:19.505657Z","steps":["trace[220938181] 'process raft request' (duration: 108.721421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:14:45.307457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.656919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-07-22T14:14:45.307556Z","caller":"traceutil/trace.go:171","msg":"trace[188217865] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:1; response_revision:3768; }","duration":"232.791682ms","start":"2026-07-22T14:14:45.074745Z","end":"2026-07-22T14:14:45.307537Z","steps":["trace[188217865] 'range keys from in-memory index tree' (duration: 232.524728ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:45.30767Z","caller":"traceutil/trace.go:171","msg":"trace[1538929618] linearizableReadLoop","detail":"{readStateIndex:3915; appliedIndex:3914; }","duration":"173.875554ms","start":"2026-07-22T14:14:45.133779Z","end":"2026-07-22T14:14:45.307655Z","steps":["trace[1538929618] 'read index received' (duration: 81.873406ms)","trace[1538929618] 'applied index is now lower than readState.Index' (duration: 92.001138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:14:45.307884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.069477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:14:45.308051Z","caller":"traceutil/trace.go:171","msg":"trace[2114649521] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3769; }","duration":"174.228558ms","start":"2026-07-22T14:14:45.133727Z","end":"2026-07-22T14:14:45.307955Z","steps":["trace[2114649521] 'agreement among raft nodes before linearized reading' (duration: 174.056796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:14:45.307928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.891299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:14:45.308202Z","caller":"traceutil/trace.go:171","msg":"trace[1991553518] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:3769; }","duration":"172.170922ms","start":"2026-07-22T14:14:45.136023Z","end":"2026-07-22T14:14:45.308194Z","steps":["trace[1991553518] 'agreement among raft nodes before linearized reading' (duration: 171.872358ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:45.308369Z","caller":"traceutil/trace.go:171","msg":"trace[784610217] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"233.515511ms","start":"2026-07-22T14:14:45.07482Z","end":"2026-07-22T14:14:45.308336Z","steps":["trace[784610217] 'process raft request' (duration: 140.881725ms)","trace[784610217] 'compare' (duration: 91.763635ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:12.111918Z","caller":"traceutil/trace.go:171","msg":"trace[1750082448] transaction","detail":"{read_only:false; response_revision:3979; number_of_response:1; }","duration":"131.381415ms","start":"2026-07-22T14:15:11.980506Z","end":"2026-07-22T14:15:12.111887Z","steps":["trace[1750082448] 'process raft request' (duration: 110.801493ms)","trace[1750082448] 'compare' (duration: 20.288768ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:49.083121Z","caller":"traceutil/trace.go:171","msg":"trace[35401102] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"143.867943ms","start":"2026-07-22T14:15:48.939221Z","end":"2026-07-22T14:15:49.083089Z","steps":["trace[35401102] 'process raft request' (duration: 137.077068ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083158Z","caller":"traceutil/trace.go:171","msg":"trace[2065668109] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"143.803902ms","start":"2026-07-22T14:15:48.939287Z","end":"2026-07-22T14:15:49.08309Z","steps":["trace[2065668109] 'process raft request' (duration: 143.68912ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083344Z","caller":"traceutil/trace.go:171","msg":"trace[844376606] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"143.60874ms","start":"2026-07-22T14:15:48.939726Z","end":"2026-07-22T14:15:49.083334Z","steps":["trace[844376606] 'process raft request' (duration: 143.428938ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083382Z","caller":"traceutil/trace.go:171","msg":"trace[1978575907] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"143.920023ms","start":"2026-07-22T14:15:48.939452Z","end":"2026-07-22T14:15:49.083372Z","steps":["trace[1978575907] 'process raft request' (duration: 143.552999ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083607Z","caller":"traceutil/trace.go:171","msg":"trace[486982461] transaction","detail":"{read_only:false; response_revision:4423; number_of_response:1; }","duration":"142.548526ms","start":"2026-07-22T14:15:48.941052Z","end":"2026-07-22T14:15:49.083601Z","steps":["trace[486982461] 'process raft request' (duration: 142.153781ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083543Z","caller":"traceutil/trace.go:171","msg":"trace[1917677973] transaction","detail":"{read_only:false; response_revision:4429; number_of_response:1; }","duration":"139.914113ms","start":"2026-07-22T14:15:48.943618Z","end":"2026-07-22T14:15:49.083532Z","steps":["trace[1917677973] 'process raft request' (duration: 139.848792ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083681Z","caller":"traceutil/trace.go:171","msg":"trace[2073212459] transaction","detail":"{read_only:false; response_revision:4424; number_of_response:1; }","duration":"142.533546ms","start":"2026-07-22T14:15:48.941143Z","end":"2026-07-22T14:15:49.083676Z","steps":["trace[2073212459] 'process raft request' (duration: 142.08324ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083572Z","caller":"traceutil/trace.go:171","msg":"trace[776277551] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"142.571317ms","start":"2026-07-22T14:15:48.940994Z","end":"2026-07-22T14:15:49.083566Z","steps":["trace[776277551] 'process raft request' (duration: 142.182522ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083779Z","caller":"traceutil/trace.go:171","msg":"trace[1003515422] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"144.146486ms","start":"2026-07-22T14:15:48.939621Z","end":"2026-07-22T14:15:49.083768Z","steps":["trace[1003515422] 'process raft request' (duration: 143.483688ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083349Z","caller":"traceutil/trace.go:171","msg":"trace[2098014749] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"143.777662ms","start":"2026-07-22T14:15:48.939561Z","end":"2026-07-22T14:15:49.083338Z","steps":["trace[2098014749] 'process raft request' (duration: 143.469378ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083855Z","caller":"traceutil/trace.go:171","msg":"trace[1549438970] transaction","detail":"{read_only:false; response_revision:4425; number_of_response:1; }","duration":"140.505061ms","start":"2026-07-22T14:15:48.943319Z","end":"2026-07-22T14:15:49.083824Z","steps":["trace[1549438970] 'process raft request' (duration: 139.926264ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083925Z","caller":"traceutil/trace.go:171","msg":"trace[631996949] transaction","detail":"{read_only:false; response_revision:4426; number_of_response:1; }","duration":"140.525851ms","start":"2026-07-22T14:15:48.943384Z","end":"2026-07-22T14:15:49.083909Z","steps":["trace[631996949] 'process raft request' (duration: 139.886323ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.083788Z","caller":"traceutil/trace.go:171","msg":"trace[2070203388] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"144.116246ms","start":"2026-07-22T14:15:48.93966Z","end":"2026-07-22T14:15:49.083776Z","steps":["trace[2070203388] 'process raft request' (duration: 143.470448ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.084153Z","caller":"traceutil/trace.go:171","msg":"trace[978379314] transaction","detail":"{read_only:false; response_revision:4428; number_of_response:1; }","duration":"140.614163ms","start":"2026-07-22T14:15:48.943524Z","end":"2026-07-22T14:15:49.084139Z","steps":["trace[978379314] 'process raft request' (duration: 139.913584ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.08417Z","caller":"traceutil/trace.go:171","msg":"trace[1210907475] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"129.549654ms","start":"2026-07-22T14:15:48.954591Z","end":"2026-07-22T14:15:49.084141Z","steps":["trace[1210907475] 'process raft request' (duration: 129.22836ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.084114Z","caller":"traceutil/trace.go:171","msg":"trace[154658312] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"140.664093ms","start":"2026-07-22T14:15:48.943439Z","end":"2026-07-22T14:15:49.084103Z","steps":["trace[154658312] 'process raft request' (duration: 139.851113ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.292232Z","caller":"traceutil/trace.go:171","msg":"trace[189165352] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"105.92995ms","start":"2026-07-22T14:15:49.18625Z","end":"2026-07-22T14:15:49.29218Z","steps":["trace[189165352] 'process raft request' (duration: 105.655876ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.332458Z","caller":"traceutil/trace.go:171","msg":"trace[848794797] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"144.740323ms","start":"2026-07-22T14:15:49.187699Z","end":"2026-07-22T14:15:49.332439Z","steps":["trace[848794797] 'process raft request' (duration: 144.596192ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.332677Z","caller":"traceutil/trace.go:171","msg":"trace[91968085] linearizableReadLoop","detail":"{readStateIndex:4631; appliedIndex:4629; }","duration":"132.990367ms","start":"2026-07-22T14:15:49.199676Z","end":"2026-07-22T14:15:49.332666Z","steps":["trace[91968085] 'read index received' (duration: 92.24383ms)","trace[91968085] 'applied index is now lower than readState.Index' (duration: 40.745587ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:49.332765Z","caller":"traceutil/trace.go:171","msg":"trace[137407990] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"112.923277ms","start":"2026-07-22T14:15:49.219813Z","end":"2026-07-22T14:15:49.332737Z","steps":["trace[137407990] 'process raft request' (duration: 112.790836ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.332884Z","caller":"traceutil/trace.go:171","msg":"trace[1497242902] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"127.432857ms","start":"2026-07-22T14:15:49.205392Z","end":"2026-07-22T14:15:49.332825Z","steps":["trace[1497242902] 'process raft request' (duration: 127.003782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:49.332897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.19686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:15:49.333055Z","caller":"traceutil/trace.go:171","msg":"trace[1574617406] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:4455; }","duration":"133.376192ms","start":"2026-07-22T14:15:49.199646Z","end":"2026-07-22T14:15:49.333023Z","steps":["trace[1574617406] 'agreement among raft nodes before linearized reading' (duration: 133.129289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:49.522933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.231838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:15:49.523129Z","caller":"traceutil/trace.go:171","msg":"trace[1368826657] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"174.12382ms","start":"2026-07-22T14:15:49.348971Z","end":"2026-07-22T14:15:49.523094Z","steps":["trace[1368826657] 'process raft request' (duration: 53.653249ms)","trace[1368826657] 'compare' (duration: 120.042965ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:49.523273Z","caller":"traceutil/trace.go:171","msg":"trace[1341832863] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"119.486849ms","start":"2026-07-22T14:15:49.403772Z","end":"2026-07-22T14:15:49.523259Z","steps":["trace[1341832863] 'process raft request' (duration: 119.432678ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.523252Z","caller":"traceutil/trace.go:171","msg":"trace[618591630] transaction","detail":"{read_only:false; response_revision:4464; number_of_response:1; }","duration":"170.931239ms","start":"2026-07-22T14:15:49.352293Z","end":"2026-07-22T14:15:49.523224Z","steps":["trace[618591630] 'process raft request' (duration: 170.734757ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.523333Z","caller":"traceutil/trace.go:171","msg":"trace[1238833865] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"128.524792ms","start":"2026-07-22T14:15:49.394798Z","end":"2026-07-22T14:15:49.523323Z","steps":["trace[1238833865] 'process raft request' (duration: 128.37277ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.523444Z","caller":"traceutil/trace.go:171","msg":"trace[836024047] transaction","detail":"{read_only:false; response_revision:4465; number_of_response:1; }","duration":"130.00712ms","start":"2026-07-22T14:15:49.393382Z","end":"2026-07-22T14:15:49.52339Z","steps":["trace[836024047] 'process raft request' (duration: 129.699896ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.562913Z","caller":"traceutil/trace.go:171","msg":"trace[867560634] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"101.287462ms","start":"2026-07-22T14:15:49.4616Z","end":"2026-07-22T14:15:49.562888Z","steps":["trace[867560634] 'process raft request' (duration: 100.915137ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.687624Z","caller":"traceutil/trace.go:171","msg":"trace[1138944956] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"103.199395ms","start":"2026-07-22T14:15:49.584388Z","end":"2026-07-22T14:15:49.687587Z","steps":["trace[1138944956] 'process raft request' (duration: 102.956732ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.687861Z","caller":"traceutil/trace.go:171","msg":"trace[1294073756] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"104.662093ms","start":"2026-07-22T14:15:49.583176Z","end":"2026-07-22T14:15:49.687838Z","steps":["trace[1294073756] 'process raft request' (duration: 40.909419ms)","trace[1294073756] 'compare' (duration: 63.024695ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:49.687946Z","caller":"traceutil/trace.go:171","msg":"trace[118620861] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"104.167427ms","start":"2026-07-22T14:15:49.583763Z","end":"2026-07-22T14:15:49.68793Z","steps":["trace[118620861] 'process raft request' (duration: 103.517549ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.688074Z","caller":"traceutil/trace.go:171","msg":"trace[735638301] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"102.542707ms","start":"2026-07-22T14:15:49.585521Z","end":"2026-07-22T14:15:49.688064Z","steps":["trace[735638301] 'process raft request' (duration: 101.9812ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.688149Z","caller":"traceutil/trace.go:171","msg":"trace[1616828780] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"103.658721ms","start":"2026-07-22T14:15:49.584482Z","end":"2026-07-22T14:15:49.68814Z","steps":["trace[1616828780] 'process raft request' (duration: 102.903632ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.68817Z","caller":"traceutil/trace.go:171","msg":"trace[1871934154] transaction","detail":"{read_only:false; response_revision:4483; number_of_response:1; }","duration":"102.72746ms","start":"2026-07-22T14:15:49.585424Z","end":"2026-07-22T14:15:49.688152Z","steps":["trace[1871934154] 'process raft request' (duration: 102.053752ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.688378Z","caller":"traceutil/trace.go:171","msg":"trace[99441899] transaction","detail":"{read_only:false; response_revision:4485; number_of_response:1; }","duration":"101.866269ms","start":"2026-07-22T14:15:49.586491Z","end":"2026-07-22T14:15:49.688357Z","steps":["trace[99441899] 'process raft request' (duration: 101.056029ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:49.688423Z","caller":"traceutil/trace.go:171","msg":"trace[892372478] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"101.610375ms","start":"2026-07-22T14:15:49.586802Z","end":"2026-07-22T14:15:49.688412Z","steps":["trace[892372478] 'process raft request' (duration: 100.767395ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:53.68951Z","caller":"traceutil/trace.go:171","msg":"trace[1827117893] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"127.986772ms","start":"2026-07-22T14:15:53.561504Z","end":"2026-07-22T14:15:53.689491Z","steps":["trace[1827117893] 'process raft request' (duration: 127.87641ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:53.689866Z","caller":"traceutil/trace.go:171","msg":"trace[1847218471] linearizableReadLoop","detail":"{readStateIndex:4815; appliedIndex:4815; }","duration":"109.905446ms","start":"2026-07-22T14:15:53.579951Z","end":"2026-07-22T14:15:53.689857Z","steps":["trace[1847218471] 'read index received' (duration: 109.901726ms)","trace[1847218471] 'applied index is now lower than readState.Index' (duration: 3.09µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:15:53.690016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.045518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-22T14:15:53.691088Z","caller":"traceutil/trace.go:171","msg":"trace[862342359] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4635; }","duration":"111.164023ms","start":"2026-07-22T14:15:53.579909Z","end":"2026-07-22T14:15:53.691073Z","steps":["trace[862342359] 'agreement among raft nodes before linearized reading' (duration: 109.987758ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:57.081334Z","caller":"traceutil/trace.go:171","msg":"trace[182624032] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"178.258602ms","start":"2026-07-22T14:15:56.903047Z","end":"2026-07-22T14:15:57.081305Z","steps":["trace[182624032] 'process raft request' (duration: 178.121141ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:57.093739Z","caller":"traceutil/trace.go:171","msg":"trace[1692856382] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"136.101518ms","start":"2026-07-22T14:15:56.957622Z","end":"2026-07-22T14:15:57.093724Z","steps":["trace[1692856382] 'process raft request' (duration: 136.022647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:00.860221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338270148403806184,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T14:16:01.04724Z","caller":"traceutil/trace.go:171","msg":"trace[1203277766] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"687.592607ms","start":"2026-07-22T14:16:00.359625Z","end":"2026-07-22T14:16:01.047218Z","steps":["trace[1203277766] 'read index received' (duration: 687.400285ms)","trace[1203277766] 'applied index is now lower than readState.Index' (duration: 191.242µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:01.04739Z","caller":"traceutil/trace.go:171","msg":"trace[1111485990] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"689.419759ms","start":"2026-07-22T14:16:00.35796Z","end":"2026-07-22T14:16:01.04738Z","steps":["trace[1111485990] 'process raft request' (duration: 689.120496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:01.047532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:00.357943Z","time spent":"689.508991ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:01.047855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.396805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-thfrr\" ","response":"range_response_count:1 size:9752"} {"level":"info","ts":"2026-07-22T14:16:01.04791Z","caller":"traceutil/trace.go:171","msg":"trace[984658601] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-thfrr; range_end:; response_count:1; response_revision:4773; }","duration":"390.479077ms","start":"2026-07-22T14:16:00.657417Z","end":"2026-07-22T14:16:01.047896Z","steps":["trace[984658601] 'agreement among raft nodes before linearized reading' (duration: 390.334625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:01.047872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.863018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T14:16:01.047942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:00.657401Z","time spent":"390.534177ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9775,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-thfrr\" "} {"level":"info","ts":"2026-07-22T14:16:01.048051Z","caller":"traceutil/trace.go:171","msg":"trace[142469021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"310.00779ms","start":"2026-07-22T14:16:00.737946Z","end":"2026-07-22T14:16:01.047954Z","steps":["trace[142469021] 'agreement among raft nodes before linearized reading' (duration: 309.831717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:01.04818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.561289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-22T14:16:01.048128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:00.73793Z","time spent":"310.181813ms","remote":"127.0.0.1:57122","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-07-22T14:16:01.048218Z","caller":"traceutil/trace.go:171","msg":"trace[1784802823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4773; }","duration":"688.6333ms","start":"2026-07-22T14:16:00.359576Z","end":"2026-07-22T14:16:01.048209Z","steps":["trace[1784802823] 'agreement among raft nodes before linearized reading' (duration: 688.071263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:01.048502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:00.359555Z","time spent":"688.877554ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T14:16:01.211812Z","caller":"traceutil/trace.go:171","msg":"trace[1850811075] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"158.702455ms","start":"2026-07-22T14:16:01.053062Z","end":"2026-07-22T14:16:01.211764Z","steps":["trace[1850811075] 'process raft request' (duration: 140.011443ms)","trace[1850811075] 'compare' (duration: 18.53788ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:01.224603Z","caller":"traceutil/trace.go:171","msg":"trace[1986775699] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"166.599543ms","start":"2026-07-22T14:16:01.057986Z","end":"2026-07-22T14:16:01.224585Z","steps":["trace[1986775699] 'process raft request' (duration: 166.38466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:01.224756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.927165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-22T14:16:01.224799Z","caller":"traceutil/trace.go:171","msg":"trace[2107732169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4776; }","duration":"115.981366ms","start":"2026-07-22T14:16:01.108808Z","end":"2026-07-22T14:16:01.224789Z","steps":["trace[2107732169] 'agreement among raft nodes before linearized reading' (duration: 115.830124ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:01.224598Z","caller":"traceutil/trace.go:171","msg":"trace[339753837] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4957; }","duration":"115.754963ms","start":"2026-07-22T14:16:01.108822Z","end":"2026-07-22T14:16:01.224577Z","steps":["trace[339753837] 'read index received' (duration: 84.262003ms)","trace[339753837] 'applied index is now lower than readState.Index' (duration: 31.49207ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:01.225055Z","caller":"traceutil/trace.go:171","msg":"trace[1041843885] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"121.617066ms","start":"2026-07-22T14:16:01.103337Z","end":"2026-07-22T14:16:01.224954Z","steps":["trace[1041843885] 'process raft request' (duration: 121.189621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:07.053379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.751633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-22T14:16:07.053454Z","caller":"traceutil/trace.go:171","msg":"trace[757321142] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030; range_end:; response_count:1; response_revision:4875; }","duration":"104.886235ms","start":"2026-07-22T14:16:06.94855Z","end":"2026-07-22T14:16:07.053436Z","steps":["trace[757321142] 'range keys from in-memory index tree' (duration: 104.592101ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:07.532272Z","caller":"traceutil/trace.go:171","msg":"trace[927016648] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"287.585331ms","start":"2026-07-22T14:16:07.244664Z","end":"2026-07-22T14:16:07.532249Z","steps":["trace[927016648] 'read index received' (duration: 287.397748ms)","trace[927016648] 'applied index is now lower than readState.Index' (duration: 186.532µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:07.532353Z","caller":"traceutil/trace.go:171","msg":"trace[470481262] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"457.636379ms","start":"2026-07-22T14:16:07.07467Z","end":"2026-07-22T14:16:07.532307Z","steps":["trace[470481262] 'process raft request' (duration: 457.403676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:07.532482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.806253ms","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-07-22T14:16:07.532522Z","caller":"traceutil/trace.go:171","msg":"trace[32203987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4876; }","duration":"287.897144ms","start":"2026-07-22T14:16:07.244615Z","end":"2026-07-22T14:16:07.532512Z","steps":["trace[32203987] 'agreement among raft nodes before linearized reading' (duration: 287.739802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:07.532538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.074654Z","time spent":"457.795442ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:08.033233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338270148403806470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T14:16:08.280122Z","caller":"traceutil/trace.go:171","msg":"trace[222915605] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"1.032340563s","start":"2026-07-22T14:16:07.247756Z","end":"2026-07-22T14:16:08.280097Z","steps":["trace[222915605] 'process raft request' (duration: 1.032128561s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.280283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.247728Z","time spent":"1.032479055s","remote":"127.0.0.1:57420","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-07-22T14:16:08.280483Z","caller":"traceutil/trace.go:171","msg":"trace[1993176423] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"888.394136ms","start":"2026-07-22T14:16:07.392059Z","end":"2026-07-22T14:16:08.280454Z","steps":["trace[1993176423] 'process raft request' (duration: 887.995381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.280671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.392044Z","time spent":"888.500128ms","remote":"127.0.0.1:57420","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-07-22T14:16:08.316317Z","caller":"traceutil/trace.go:171","msg":"trace[1931548970] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5062; }","duration":"783.957956ms","start":"2026-07-22T14:16:07.532335Z","end":"2026-07-22T14:16:08.316293Z","steps":["trace[1931548970] 'read index received' (duration: 747.520546ms)","trace[1931548970] 'applied index is now lower than readState.Index' (duration: 36.43661ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:08.316572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.839545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:16:08.316659Z","caller":"traceutil/trace.go:171","msg":"trace[853727829] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:4880; }","duration":"864.976906ms","start":"2026-07-22T14:16:07.451662Z","end":"2026-07-22T14:16:08.316639Z","steps":["trace[853727829] 'agreement among raft nodes before linearized reading' (duration: 864.758594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.316709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.451636Z","time spent":"865.062718ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":78,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:16:08.316792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.282928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T14:16:08.31684Z","caller":"traceutil/trace.go:171","msg":"trace[1428725678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4880; }","duration":"975.37498ms","start":"2026-07-22T14:16:07.341454Z","end":"2026-07-22T14:16:08.316829Z","steps":["trace[1428725678] 'agreement among raft nodes before linearized reading' (duration: 975.145647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.316877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.341437Z","time spent":"975.43264ms","remote":"127.0.0.1:57420","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":"info","ts":"2026-07-22T14:16:08.316984Z","caller":"traceutil/trace.go:171","msg":"trace[523208896] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"779.226228ms","start":"2026-07-22T14:16:07.537741Z","end":"2026-07-22T14:16:08.316967Z","steps":["trace[523208896] 'process raft request' (duration: 778.373708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.537707Z","time spent":"779.326379ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:08.317204Z","caller":"traceutil/trace.go:171","msg":"trace[657627824] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"601.840198ms","start":"2026-07-22T14:16:07.715353Z","end":"2026-07-22T14:16:08.317193Z","steps":["trace[657627824] 'process raft request' (duration: 600.892876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.564142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-22T14:16:08.317257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.715342Z","time spent":"601.889319ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T14:16:08.317269Z","caller":"traceutil/trace.go:171","msg":"trace[491414920] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:4880; }","duration":"783.690423ms","start":"2026-07-22T14:16:07.533562Z","end":"2026-07-22T14:16:08.317253Z","steps":["trace[491414920] 'agreement among raft nodes before linearized reading' (duration: 783.4657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.533545Z","time spent":"783.752853ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-07-22T14:16:08.317474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.118254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-22T14:16:08.317507Z","caller":"traceutil/trace.go:171","msg":"trace[1164576537] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4880; }","duration":"398.178214ms","start":"2026-07-22T14:16:07.919317Z","end":"2026-07-22T14:16:08.317495Z","steps":["trace[1164576537] 'agreement among raft nodes before linearized reading' (duration: 398.077273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.9193Z","time spent":"398.225664ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-22T14:16:08.317538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.703035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-22T14:16:08.317571Z","caller":"traceutil/trace.go:171","msg":"trace[1639496701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4880; }","duration":"481.735126ms","start":"2026-07-22T14:16:07.835829Z","end":"2026-07-22T14:16:08.317564Z","steps":["trace[1639496701] 'agreement among raft nodes before linearized reading' (duration: 481.651805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.835806Z","time spent":"481.782486ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:16:08.317736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.474887ms","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-07-22T14:16:08.317756Z","caller":"traceutil/trace.go:171","msg":"trace[1301495646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4880; }","duration":"483.529027ms","start":"2026-07-22T14:16:07.834221Z","end":"2026-07-22T14:16:08.31775Z","steps":["trace[1301495646] 'agreement among raft nodes before linearized reading' (duration: 483.462616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.834193Z","time spent":"483.576738ms","remote":"127.0.0.1:57420","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-07-22T14:16:08.317917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.857237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:08.317934Z","caller":"traceutil/trace.go:171","msg":"trace[455113051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4880; }","duration":"579.904267ms","start":"2026-07-22T14:16:07.738025Z","end":"2026-07-22T14:16:08.317929Z","steps":["trace[455113051] 'agreement among raft nodes before linearized reading' (duration: 579.866917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.957207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-22T14:16:08.317955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.738005Z","time spent":"579.943058ms","remote":"127.0.0.1:57122","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-07-22T14:16:08.31796Z","caller":"traceutil/trace.go:171","msg":"trace[199464828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4880; }","duration":"122.986788ms","start":"2026-07-22T14:16:08.194966Z","end":"2026-07-22T14:16:08.317953Z","steps":["trace[199464828] 'agreement among raft nodes before linearized reading' (duration: 122.877746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.317997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.169358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-22T14:16:08.318047Z","caller":"traceutil/trace.go:171","msg":"trace[189309764] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030; range_end:; response_count:1; response_revision:4880; }","duration":"259.25999ms","start":"2026-07-22T14:16:08.058774Z","end":"2026-07-22T14:16:08.318034Z","steps":["trace[189309764] 'agreement among raft nodes before linearized reading' (duration: 259.096728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.318115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.047276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-22T14:16:08.318142Z","caller":"traceutil/trace.go:171","msg":"trace[414748769] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39164345-7d02-49a6-b855-b94a6a6d5030; range_end:; response_count:1; response_revision:4880; }","duration":"604.301019ms","start":"2026-07-22T14:16:07.713832Z","end":"2026-07-22T14:16:08.318133Z","steps":["trace[414748769] 'agreement among raft nodes before linearized reading' (duration: 604.220828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:08.318161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:07.713815Z","time spent":"604.340759ms","remote":"127.0.0.1:57338","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-39164345-7d02-49a6-b855-b94a6a6d5030\" "} {"level":"info","ts":"2026-07-22T14:16:08.493206Z","caller":"traceutil/trace.go:171","msg":"trace[1301567026] linearizableReadLoop","detail":"{readStateIndex:5072; appliedIndex:5066; }","duration":"170.217551ms","start":"2026-07-22T14:16:08.322959Z","end":"2026-07-22T14:16:08.493177Z","steps":["trace[1301567026] 'read index received' (duration: 53.974076ms)","trace[1301567026] 'applied index is now lower than readState.Index' (duration: 116.241685ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:08.493482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.491854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-07-22T14:16:08.493538Z","caller":"traceutil/trace.go:171","msg":"trace[1726214664] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4886; }","duration":"170.595905ms","start":"2026-07-22T14:16:08.32293Z","end":"2026-07-22T14:16:08.493526Z","steps":["trace[1726214664] 'agreement among raft nodes before linearized reading' (duration: 170.355292ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.493619Z","caller":"traceutil/trace.go:171","msg":"trace[438884894] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"170.700607ms","start":"2026-07-22T14:16:08.322879Z","end":"2026-07-22T14:16:08.493579Z","steps":["trace[438884894] 'process raft request' (duration: 170.203201ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.493829Z","caller":"traceutil/trace.go:171","msg":"trace[1045422594] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"171.093372ms","start":"2026-07-22T14:16:08.322727Z","end":"2026-07-22T14:16:08.493821Z","steps":["trace[1045422594] 'process raft request' (duration: 170.318822ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.493895Z","caller":"traceutil/trace.go:171","msg":"trace[2100357920] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"173.247827ms","start":"2026-07-22T14:16:08.320637Z","end":"2026-07-22T14:16:08.493884Z","steps":["trace[2100357920] 'process raft request' (duration: 172.128714ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.493994Z","caller":"traceutil/trace.go:171","msg":"trace[1817501884] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"168.085594ms","start":"2026-07-22T14:16:08.325896Z","end":"2026-07-22T14:16:08.493981Z","steps":["trace[1817501884] 'process raft request' (duration: 167.220123ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.494094Z","caller":"traceutil/trace.go:171","msg":"trace[773321168] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"171.460696ms","start":"2026-07-22T14:16:08.32262Z","end":"2026-07-22T14:16:08.494081Z","steps":["trace[773321168] 'process raft request' (duration: 170.328822ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.493492Z","caller":"traceutil/trace.go:171","msg":"trace[1964830859] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"170.774307ms","start":"2026-07-22T14:16:08.322684Z","end":"2026-07-22T14:16:08.493458Z","steps":["trace[1964830859] 'process raft request' (duration: 170.328392ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:08.578802Z","caller":"traceutil/trace.go:171","msg":"trace[1234832848] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"175.703749ms","start":"2026-07-22T14:16:08.403045Z","end":"2026-07-22T14:16:08.578749Z","steps":["trace[1234832848] 'process raft request' (duration: 107.508077ms)","trace[1234832848] 'compare' (duration: 67.967219ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:08.79495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.915103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-07-22T14:16:08.795825Z","caller":"traceutil/trace.go:171","msg":"trace[809302509] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4889; }","duration":"113.854144ms","start":"2026-07-22T14:16:08.681938Z","end":"2026-07-22T14:16:08.795792Z","steps":["trace[809302509] 'range keys from in-memory index tree' (duration: 112.634079ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:09.124688Z","caller":"traceutil/trace.go:171","msg":"trace[967040340] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"322.62613ms","start":"2026-07-22T14:16:08.802036Z","end":"2026-07-22T14:16:09.124662Z","steps":["trace[967040340] 'read index received' (duration: 322.451438ms)","trace[967040340] 'applied index is now lower than readState.Index' (duration: 173.712µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:09.124724Z","caller":"traceutil/trace.go:171","msg":"trace[1070225832] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"324.25689ms","start":"2026-07-22T14:16:08.800435Z","end":"2026-07-22T14:16:09.124692Z","steps":["trace[1070225832] 'process raft request' (duration: 324.075138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:09.124904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:08.800399Z","time spent":"324.407373ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:09.124968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.930064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18c4a24429a11a03\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-07-22T14:16:09.125011Z","caller":"traceutil/trace.go:171","msg":"trace[193053047] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18c4a24429a11a03; range_end:; response_count:1; response_revision:4890; }","duration":"323.039475ms","start":"2026-07-22T14:16:08.801958Z","end":"2026-07-22T14:16:09.124998Z","steps":["trace[193053047] 'agreement among raft nodes before linearized reading' (duration: 322.815562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:09.125047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:08.80193Z","time spent":"323.108517ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":774,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18c4a24429a11a03\" "} {"level":"info","ts":"2026-07-22T14:16:09.163735Z","caller":"traceutil/trace.go:171","msg":"trace[394951764] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"325.86018ms","start":"2026-07-22T14:16:08.837846Z","end":"2026-07-22T14:16:09.163706Z","steps":["trace[394951764] 'process raft request' (duration: 325.758339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:09.163881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:08.837821Z","time spent":"325.975301ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:09.253052Z","caller":"traceutil/trace.go:171","msg":"trace[1271573859] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5078; }","duration":"114.923097ms","start":"2026-07-22T14:16:09.138103Z","end":"2026-07-22T14:16:09.253026Z","steps":["trace[1271573859] 'read index received' (duration: 25.645016ms)","trace[1271573859] 'applied index is now lower than readState.Index' (duration: 89.276881ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:09.25307Z","caller":"traceutil/trace.go:171","msg":"trace[934294198] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"125.264665ms","start":"2026-07-22T14:16:09.127784Z","end":"2026-07-22T14:16:09.253048Z","steps":["trace[934294198] 'process raft request' (duration: 84.046927ms)","trace[934294198] 'compare' (duration: 40.988926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:09.253327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.216311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-c77fdcb8b-lv6tx.18c4a24490962bf8\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-07-22T14:16:09.253411Z","caller":"traceutil/trace.go:171","msg":"trace[1918232061] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-c77fdcb8b-lv6tx.18c4a24490962bf8; range_end:; response_count:1; response_revision:4892; }","duration":"115.358373ms","start":"2026-07-22T14:16:09.138037Z","end":"2026-07-22T14:16:09.253396Z","steps":["trace[1918232061] 'agreement among raft nodes before linearized reading' (duration: 115.17404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:09.768739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.924804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:16:09.76891Z","caller":"traceutil/trace.go:171","msg":"trace[1323663692] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"231.392392ms","start":"2026-07-22T14:16:09.537497Z","end":"2026-07-22T14:16:09.76889Z","steps":["trace[1323663692] 'read index received' (duration: 113.042923ms)","trace[1323663692] 'applied index is now lower than readState.Index' (duration: 118.347239ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:09.769055Z","caller":"traceutil/trace.go:171","msg":"trace[9211932] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"349.394158ms","start":"2026-07-22T14:16:09.419622Z","end":"2026-07-22T14:16:09.769016Z","steps":["trace[9211932] 'process raft request' (duration: 230.916447ms)","trace[9211932] 'compare' (duration: 117.335607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:09.769288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:09.419563Z","time spent":"349.610821ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:09.769299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.785658ms","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-07-22T14:16:09.769553Z","caller":"traceutil/trace.go:171","msg":"trace[845281602] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4895; }","duration":"232.074081ms","start":"2026-07-22T14:16:09.537443Z","end":"2026-07-22T14:16:09.769517Z","steps":["trace[845281602] 'agreement among raft nodes before linearized reading' (duration: 231.599685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.421358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338270148403806866,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T14:16:22.575714Z","caller":"traceutil/trace.go:171","msg":"trace[45318237] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"740.964437ms","start":"2026-07-22T14:16:21.834722Z","end":"2026-07-22T14:16:22.575686Z","steps":["trace[45318237] 'process raft request' (duration: 740.776555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.575904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.834706Z","time spent":"741.102099ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10666,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:22.591581Z","caller":"traceutil/trace.go:171","msg":"trace[611728002] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5237; }","duration":"670.866987ms","start":"2026-07-22T14:16:21.920697Z","end":"2026-07-22T14:16:22.591564Z","steps":["trace[611728002] 'read index received' (duration: 654.865871ms)","trace[611728002] 'applied index is now lower than readState.Index' (duration: 16.000316ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:22.591765Z","caller":"traceutil/trace.go:171","msg":"trace[641965581] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"261.75457ms","start":"2026-07-22T14:16:22.329998Z","end":"2026-07-22T14:16:22.591753Z","steps":["trace[641965581] 'process raft request' (duration: 261.529987ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.591705Z","caller":"traceutil/trace.go:171","msg":"trace[2118350023] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"262.436398ms","start":"2026-07-22T14:16:22.329232Z","end":"2026-07-22T14:16:22.591668Z","steps":["trace[2118350023] 'process raft request' (duration: 262.201685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.592623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.903199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-22T14:16:22.5927Z","caller":"traceutil/trace.go:171","msg":"trace[1807923999] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5048; }","duration":"672.03474ms","start":"2026-07-22T14:16:21.920643Z","end":"2026-07-22T14:16:22.592678Z","steps":["trace[1807923999] 'agreement among raft nodes before linearized reading' (duration: 671.136329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.592757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.920622Z","time spent":"672.122322ms","remote":"127.0.0.1:57312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-22T14:16:22.683235Z","caller":"traceutil/trace.go:171","msg":"trace[1266305422] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"100.188598ms","start":"2026-07-22T14:16:22.583017Z","end":"2026-07-22T14:16:22.683206Z","steps":["trace[1266305422] 'process raft request' (duration: 99.846084ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.683262Z","caller":"traceutil/trace.go:171","msg":"trace[114641205] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"100.219019ms","start":"2026-07-22T14:16:22.583016Z","end":"2026-07-22T14:16:22.683235Z","steps":["trace[114641205] 'process raft request' (duration: 100.088717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.68366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.34802ms","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-07-22T14:16:22.683727Z","caller":"traceutil/trace.go:171","msg":"trace[550015511] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5052; }","duration":"175.425571ms","start":"2026-07-22T14:16:22.508284Z","end":"2026-07-22T14:16:22.68371Z","steps":["trace[550015511] 'agreement among raft nodes before linearized reading' (duration: 175.271939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.683738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.849155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-22T14:16:22.683798Z","caller":"traceutil/trace.go:171","msg":"trace[941810010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5052; }","duration":"307.958016ms","start":"2026-07-22T14:16:22.375823Z","end":"2026-07-22T14:16:22.683781Z","steps":["trace[941810010] 'agreement among raft nodes before linearized reading' (duration: 307.758083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.683847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:22.375803Z","time spent":"308.032997ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T14:16:22.683861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.986687ms","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-07-22T14:16:22.683902Z","caller":"traceutil/trace.go:171","msg":"trace[1657467021] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"100.570343ms","start":"2026-07-22T14:16:22.583322Z","end":"2026-07-22T14:16:22.683892Z","steps":["trace[1657467021] 'process raft request' (duration: 99.863464ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.68391Z","caller":"traceutil/trace.go:171","msg":"trace[327822624] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5052; }","duration":"715.074659ms","start":"2026-07-22T14:16:21.968817Z","end":"2026-07-22T14:16:22.683892Z","steps":["trace[327822624] 'agreement among raft nodes before linearized reading' (duration: 714.916727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.683947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.968799Z","time spent":"715.140139ms","remote":"127.0.0.1:57420","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-07-22T14:16:22.684057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.083231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:22.684103Z","caller":"traceutil/trace.go:171","msg":"trace[1049051788] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5052; }","duration":"513.168722ms","start":"2026-07-22T14:16:22.170926Z","end":"2026-07-22T14:16:22.684095Z","steps":["trace[1049051788] 'agreement among raft nodes before linearized reading' (duration: 513.107022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.68413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:22.170904Z","time spent":"513.219073ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-22T14:16:22.684148Z","caller":"traceutil/trace.go:171","msg":"trace[483285174] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"100.366281ms","start":"2026-07-22T14:16:22.583773Z","end":"2026-07-22T14:16:22.684139Z","steps":["trace[483285174] 'process raft request' (duration: 99.661212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.684311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.831151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T14:16:22.684338Z","caller":"traceutil/trace.go:171","msg":"trace[1977420365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5052; }","duration":"102.898031ms","start":"2026-07-22T14:16:22.581433Z","end":"2026-07-22T14:16:22.684332Z","steps":["trace[1977420365] 'agreement among raft nodes before linearized reading' (duration: 102.80759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.684346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.535906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:22.684397Z","caller":"traceutil/trace.go:171","msg":"trace[644149686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5052; }","duration":"663.622027ms","start":"2026-07-22T14:16:22.020761Z","end":"2026-07-22T14:16:22.684383Z","steps":["trace[644149686] 'agreement among raft nodes before linearized reading' (duration: 663.545776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.684437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:22.02074Z","time spent":"663.690398ms","remote":"127.0.0.1:57122","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-07-22T14:16:22.684398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.022784ms","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-07-22T14:16:22.684531Z","caller":"traceutil/trace.go:171","msg":"trace[1442340811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5052; }","duration":"744.188495ms","start":"2026-07-22T14:16:21.940316Z","end":"2026-07-22T14:16:22.684505Z","steps":["trace[1442340811] 'agreement among raft nodes before linearized reading' (duration: 744.018663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.684584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.940302Z","time spent":"744.267046ms","remote":"127.0.0.1:48146","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":"info","ts":"2026-07-22T14:16:22.840644Z","caller":"traceutil/trace.go:171","msg":"trace[949482587] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"150.121371ms","start":"2026-07-22T14:16:22.690504Z","end":"2026-07-22T14:16:22.840626Z","steps":["trace[949482587] 'process raft request' (duration: 150.06279ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.840649Z","caller":"traceutil/trace.go:171","msg":"trace[1171824425] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"150.346304ms","start":"2026-07-22T14:16:22.69027Z","end":"2026-07-22T14:16:22.840616Z","steps":["trace[1171824425] 'process raft request' (duration: 70.629976ms)","trace[1171824425] 'compare' (duration: 79.562826ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:22.840845Z","caller":"traceutil/trace.go:171","msg":"trace[1471849840] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"150.0663ms","start":"2026-07-22T14:16:22.69076Z","end":"2026-07-22T14:16:22.840826Z","steps":["trace[1471849840] 'process raft request' (duration: 149.835868ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.841009Z","caller":"traceutil/trace.go:171","msg":"trace[358416185] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"150.135661ms","start":"2026-07-22T14:16:22.690866Z","end":"2026-07-22T14:16:22.841001Z","steps":["trace[358416185] 'process raft request' (duration: 149.901348ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.841205Z","caller":"traceutil/trace.go:171","msg":"trace[1796591566] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"150.279533ms","start":"2026-07-22T14:16:22.690915Z","end":"2026-07-22T14:16:22.841195Z","steps":["trace[1796591566] 'process raft request' (duration: 150.02345ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.841318Z","caller":"traceutil/trace.go:171","msg":"trace[421073168] linearizableReadLoop","detail":"{readStateIndex:5251; appliedIndex:5245; }","duration":"103.930384ms","start":"2026-07-22T14:16:22.737378Z","end":"2026-07-22T14:16:22.841309Z","steps":["trace[421073168] 'read index received' (duration: 23.418627ms)","trace[421073168] 'applied index is now lower than readState.Index' (duration: 80.511057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:22.841383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.005746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:22.841984Z","caller":"traceutil/trace.go:171","msg":"trace[53768061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5059; }","duration":"104.662643ms","start":"2026-07-22T14:16:22.737308Z","end":"2026-07-22T14:16:22.841971Z","steps":["trace[53768061] 'agreement among raft nodes before linearized reading' (duration: 104.026676ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:22.841414Z","caller":"traceutil/trace.go:171","msg":"trace[55754853] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"149.917309ms","start":"2026-07-22T14:16:22.69149Z","end":"2026-07-22T14:16:22.841407Z","steps":["trace[55754853] 'process raft request' (duration: 149.666905ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:44.771439Z","caller":"traceutil/trace.go:171","msg":"trace[1686087749] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"101.16123ms","start":"2026-07-22T14:16:44.670207Z","end":"2026-07-22T14:16:44.771368Z","steps":["trace[1686087749] 'process raft request' (duration: 100.925437ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:44.962212Z","caller":"traceutil/trace.go:171","msg":"trace[1065250388] linearizableReadLoop","detail":"{readStateIndex:5497; appliedIndex:5496; }","duration":"154.813722ms","start":"2026-07-22T14:16:44.807378Z","end":"2026-07-22T14:16:44.962191Z","steps":["trace[1065250388] 'read index received' (duration: 141.978746ms)","trace[1065250388] 'applied index is now lower than readState.Index' (duration: 12.833736ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:44.962288Z","caller":"traceutil/trace.go:171","msg":"trace[694955693] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"179.005206ms","start":"2026-07-22T14:16:44.783249Z","end":"2026-07-22T14:16:44.962254Z","steps":["trace[694955693] 'process raft request' (duration: 166.16819ms)","trace[694955693] 'compare' (duration: 12.675634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:44.962429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.518485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T14:16:44.962483Z","caller":"traceutil/trace.go:171","msg":"trace[705625008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5299; }","duration":"125.616967ms","start":"2026-07-22T14:16:44.83685Z","end":"2026-07-22T14:16:44.962467Z","steps":["trace[705625008] 'agreement among raft nodes before linearized reading' (duration: 125.459795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.962816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.430829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-22T14:16:44.962913Z","caller":"traceutil/trace.go:171","msg":"trace[1328845289] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5299; }","duration":"155.597931ms","start":"2026-07-22T14:16:44.807293Z","end":"2026-07-22T14:16:44.962891Z","steps":["trace[1328845289] 'agreement among raft nodes before linearized reading' (duration: 154.975584ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:48.151764Z","caller":"traceutil/trace.go:171","msg":"trace[1933263306] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"184.47174ms","start":"2026-07-22T14:16:47.967263Z","end":"2026-07-22T14:16:48.151735Z","steps":["trace[1933263306] 'process raft request' (duration: 184.288708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:59.115866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.759384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:16:59.116314Z","caller":"traceutil/trace.go:171","msg":"trace[616512700] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"142.120539ms","start":"2026-07-22T14:16:58.974075Z","end":"2026-07-22T14:16:59.116195Z","steps":["trace[616512700] 'process raft request' (duration: 141.915296ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:59.116261Z","caller":"traceutil/trace.go:171","msg":"trace[1082159969] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"213.896327ms","start":"2026-07-22T14:16:58.902268Z","end":"2026-07-22T14:16:59.116165Z","steps":["trace[1082159969] 'process raft request' (duration: 52.584566ms)","trace[1082159969] 'compare' (duration: 160.631802ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:04.345687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-07-22T14:17:04.419899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"72.802869ms","hash":833272952,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":29052928,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-22T14:17:04.419962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":833272952,"revision":2843,"compact-revision":-1} {"level":"info","ts":"2026-07-22T14:17:10.960434Z","caller":"traceutil/trace.go:171","msg":"trace[748834742] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"112.195062ms","start":"2026-07-22T14:17:10.848208Z","end":"2026-07-22T14:17:10.960403Z","steps":["trace[748834742] 'process raft request' (duration: 111.991809ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.35078Z","caller":"traceutil/trace.go:171","msg":"trace[1156377609] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5694; }","duration":"137.499136ms","start":"2026-07-22T14:17:13.213255Z","end":"2026-07-22T14:17:13.350754Z","steps":["trace[1156377609] 'read index received' (duration: 98.83541ms)","trace[1156377609] 'applied index is now lower than readState.Index' (duration: 38.662686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:17:13.351032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.522456ms","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-07-22T14:17:13.351033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.145167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T14:17:13.351067Z","caller":"traceutil/trace.go:171","msg":"trace[2115612985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5489; }","duration":"132.592396ms","start":"2026-07-22T14:17:13.218463Z","end":"2026-07-22T14:17:13.351055Z","steps":["trace[2115612985] 'agreement among raft nodes before linearized reading' (duration: 132.433154ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.351082Z","caller":"traceutil/trace.go:171","msg":"trace[1132182740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5489; }","duration":"120.243678ms","start":"2026-07-22T14:17:13.230824Z","end":"2026-07-22T14:17:13.351068Z","steps":["trace[1132182740] 'agreement among raft nodes before linearized reading' (duration: 120.078216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:13.351123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.032678ms","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":"warn","ts":"2026-07-22T14:17:13.351131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.82972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-22T14:17:13.351198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.30591ms","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-07-22T14:17:13.350926Z","caller":"traceutil/trace.go:171","msg":"trace[1085719314] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"142.166181ms","start":"2026-07-22T14:17:13.208721Z","end":"2026-07-22T14:17:13.350887Z","steps":["trace[1085719314] 'process raft request' (duration: 103.406785ms)","trace[1085719314] 'compare' (duration: 38.507763ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:13.351257Z","caller":"traceutil/trace.go:171","msg":"trace[2146415357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5489; }","duration":"116.379612ms","start":"2026-07-22T14:17:13.234868Z","end":"2026-07-22T14:17:13.351247Z","steps":["trace[2146415357] 'agreement among raft nodes before linearized reading' (duration: 116.303241ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.351262Z","caller":"traceutil/trace.go:171","msg":"trace[1420248731] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5489; }","duration":"138.003412ms","start":"2026-07-22T14:17:13.21323Z","end":"2026-07-22T14:17:13.351233Z","steps":["trace[1420248731] 'agreement among raft nodes before linearized reading' (duration: 137.619397ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.351186Z","caller":"traceutil/trace.go:171","msg":"trace[1252253305] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5489; }","duration":"126.124239ms","start":"2026-07-22T14:17:13.225048Z","end":"2026-07-22T14:17:13.351172Z","steps":["trace[1252253305] 'agreement among raft nodes before linearized reading' (duration: 125.894216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:13.351125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.773475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T14:17:13.35134Z","caller":"traceutil/trace.go:171","msg":"trace[2023320349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5489; }","duration":"131.022078ms","start":"2026-07-22T14:17:13.220301Z","end":"2026-07-22T14:17:13.351323Z","steps":["trace[2023320349] 'agreement among raft nodes before linearized reading' (duration: 130.649063ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.514489Z","caller":"traceutil/trace.go:171","msg":"trace[2009259926] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"157.665118ms","start":"2026-07-22T14:17:13.356805Z","end":"2026-07-22T14:17:13.51447Z","steps":["trace[2009259926] 'process raft request' (duration: 104.573949ms)","trace[2009259926] 'compare' (duration: 52.962048ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:13.514752Z","caller":"traceutil/trace.go:171","msg":"trace[1737707493] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"157.678489ms","start":"2026-07-22T14:17:13.357064Z","end":"2026-07-22T14:17:13.514742Z","steps":["trace[1737707493] 'process raft request' (duration: 157.375775ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.514834Z","caller":"traceutil/trace.go:171","msg":"trace[393738462] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"156.679626ms","start":"2026-07-22T14:17:13.358131Z","end":"2026-07-22T14:17:13.51481Z","steps":["trace[393738462] 'process raft request' (duration: 156.639626ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.514891Z","caller":"traceutil/trace.go:171","msg":"trace[2126263606] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"157.76779ms","start":"2026-07-22T14:17:13.357098Z","end":"2026-07-22T14:17:13.514866Z","steps":["trace[2126263606] 'process raft request' (duration: 157.534087ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:13.515014Z","caller":"traceutil/trace.go:171","msg":"trace[878575551] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"157.340615ms","start":"2026-07-22T14:17:13.357666Z","end":"2026-07-22T14:17:13.515006Z","steps":["trace[878575551] 'process raft request' (duration: 157.070062ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:19:46.450275Z","caller":"traceutil/trace.go:171","msg":"trace[306507294] transaction","detail":"{read_only:false; response_revision:6569; number_of_response:1; }","duration":"137.17832ms","start":"2026-07-22T14:19:46.313073Z","end":"2026-07-22T14:19:46.450251Z","steps":["trace[306507294] 'process raft request' (duration: 137.045167ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:19:46.589313Z","caller":"traceutil/trace.go:171","msg":"trace[600427128] linearizableReadLoop","detail":"{readStateIndex:6810; appliedIndex:6809; }","duration":"233.524829ms","start":"2026-07-22T14:19:46.355762Z","end":"2026-07-22T14:19:46.589287Z","steps":["trace[600427128] 'read index received' (duration: 94.475343ms)","trace[600427128] 'applied index is now lower than readState.Index' (duration: 139.048475ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:19:46.589431Z","caller":"traceutil/trace.go:171","msg":"trace[510606091] transaction","detail":"{read_only:false; response_revision:6570; number_of_response:1; }","duration":"276.125794ms","start":"2026-07-22T14:19:46.313294Z","end":"2026-07-22T14:19:46.58942Z","steps":["trace[510606091] 'process raft request' (duration: 222.869322ms)","trace[510606091] 'compare' (duration: 52.803301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:19:46.589659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.876207ms","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-07-22T14:19:46.589701Z","caller":"traceutil/trace.go:171","msg":"trace[2145920488] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6570; }","duration":"233.967949ms","start":"2026-07-22T14:19:46.355721Z","end":"2026-07-22T14:19:46.589689Z","steps":["trace[2145920488] 'agreement among raft nodes before linearized reading' (duration: 233.818776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:19:46.589945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.043347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-22T14:19:46.590045Z","caller":"traceutil/trace.go:171","msg":"trace[1166549505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6570; }","duration":"201.17785ms","start":"2026-07-22T14:19:46.38884Z","end":"2026-07-22T14:19:46.590018Z","steps":["trace[1166549505] 'agreement among raft nodes before linearized reading' (duration: 200.949985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:19:46.590192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.180668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-22T14:19:46.59025Z","caller":"traceutil/trace.go:171","msg":"trace[965315805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6570; }","duration":"198.27755ms","start":"2026-07-22T14:19:46.391957Z","end":"2026-07-22T14:19:46.590235Z","steps":["trace[965315805] 'agreement among raft nodes before linearized reading' (duration: 198.143647ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:19:56.778615Z","caller":"traceutil/trace.go:171","msg":"trace[235660855] transaction","detail":"{read_only:false; response_revision:6629; number_of_response:1; }","duration":"100.372454ms","start":"2026-07-22T14:19:56.678209Z","end":"2026-07-22T14:19:56.778581Z","steps":["trace[235660855] 'process raft request' (duration: 79.754526ms)","trace[235660855] 'compare' (duration: 20.316521ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:22:04.353589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5443} {"level":"info","ts":"2026-07-22T14:22:04.435231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5443,"took":"80.223983ms","hash":1330544595,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":18579456,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-22T14:22:04.435375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1330544595,"revision":5443,"compact-revision":2843}