{"level":"warn","ts":"2026-07-13T20:02:50.171119Z","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-13T20:02:50.171215Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-13T20:02:50.171291Z","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-13T20:02:50.171306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-13T20:02:50.171323Z","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-13T20:02:50.171954Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-13T20:02:50.172028Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-13T20:02:50.174813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.561803ms"} {"level":"info","ts":"2026-07-13T20:02:50.179871Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-13T20:02:50.17995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-13T20:02:50.179973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-13T20:02:50.179986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-13T20:02:50.179995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-13T20:02:50.180029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-13T20:02:50.189734Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-13T20:02:50.191432Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-13T20:02:50.193744Z","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-13T20:02:50.196746Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-13T20:02:50.196945Z","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-13T20:02:50.19708Z","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-13T20:02:50.197093Z","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-13T20:02:50.197157Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-13T20:02:50.198423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-13T20:02:50.19879Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-13T20:02:50.202715Z","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-13T20:02:50.203169Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-13T20:02:50.203034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-13T20:02:50.20413Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-13T20:02:50.203074Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-13T20:02:50.381158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-13T20:02:50.381211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-13T20:02:50.381226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-13T20:02:50.38124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-13T20:02:50.381247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-13T20:02:50.381257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-13T20:02:50.381265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-13T20:02:50.382561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-13T20:02:50.38293Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:02:50.383855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T20:02:50.384004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T20:02:50.384129Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-13T20:02:50.384326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-13T20:02:50.384133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:02:50.384527Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:02:50.384577Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:02:50.38601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-13T20:02:50.387616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-13T20:03:26.174633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.292825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:03:26.174919Z","caller":"traceutil/trace.go:171","msg":"trace[942032407] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"306.071022ms","start":"2026-07-13T20:03:25.868809Z","end":"2026-07-13T20:03:26.17488Z","steps":["trace[942032407] 'process raft request' (duration: 103.957646ms)","trace[942032407] 'compare' (duration: 201.157562ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:26.175063Z","caller":"traceutil/trace.go:171","msg":"trace[959239575] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"275.811925ms","start":"2026-07-13T20:03:25.899233Z","end":"2026-07-13T20:03:26.175045Z","steps":["trace[959239575] 'process raft request' (duration: 275.753464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:26.175132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:03:25.868788Z","time spent":"306.243156ms","remote":"127.0.0.1:35236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:03:26.175179Z","caller":"traceutil/trace.go:171","msg":"trace[24589470] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"279.587318ms","start":"2026-07-13T20:03:25.89554Z","end":"2026-07-13T20:03:26.175127Z","steps":["trace[24589470] 'process raft request' (duration: 279.340382ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:03:31.709882Z","caller":"traceutil/trace.go:171","msg":"trace[348298599] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"102.688253ms","start":"2026-07-13T20:03:31.60716Z","end":"2026-07-13T20:03:31.709849Z","steps":["trace[348298599] 'read index received' (duration: 101.76325ms)","trace[348298599] 'applied index is now lower than readState.Index' (duration: 923.493µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:31.709965Z","caller":"traceutil/trace.go:171","msg":"trace[1709824371] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"153.573449ms","start":"2026-07-13T20:03:31.556339Z","end":"2026-07-13T20:03:31.709912Z","steps":["trace[1709824371] 'process raft request' (duration: 152.81992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:31.71018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.994642ms","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-13T20:03:31.710231Z","caller":"traceutil/trace.go:171","msg":"trace[651202302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"103.106833ms","start":"2026-07-13T20:03:31.60711Z","end":"2026-07-13T20:03:31.710217Z","steps":["trace[651202302] 'agreement among raft nodes before linearized reading' (duration: 102.913469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:32.155966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.943251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:03:32.156225Z","caller":"traceutil/trace.go:171","msg":"trace[728890987] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"441.221177ms","start":"2026-07-13T20:03:31.714978Z","end":"2026-07-13T20:03:32.156199Z","steps":["trace[728890987] 'process raft request' (duration: 48.949979ms)","trace[728890987] 'compare' (duration: 391.792706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:03:32.156316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:03:31.714939Z","time spent":"441.33991ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:03:32.156531Z","caller":"traceutil/trace.go:171","msg":"trace[493814393] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"440.824686ms","start":"2026-07-13T20:03:31.715685Z","end":"2026-07-13T20:03:32.15651Z","steps":["trace[493814393] 'process raft request' (duration: 440.434897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:32.156635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:03:31.715662Z","time spent":"440.921218ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:03:32.156708Z","caller":"traceutil/trace.go:171","msg":"trace[1979163461] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"414.990059ms","start":"2026-07-13T20:03:31.741636Z","end":"2026-07-13T20:03:32.156626Z","steps":["trace[1979163461] 'read index received' (duration: 22.316191ms)","trace[1979163461] 'applied index is now lower than readState.Index' (duration: 392.658738ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:32.156912Z","caller":"traceutil/trace.go:171","msg":"trace[1676289573] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"379.892042ms","start":"2026-07-13T20:03:31.77699Z","end":"2026-07-13T20:03:32.156882Z","steps":["trace[1676289573] 'process raft request' (duration: 379.512633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:32.156985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:03:31.776969Z","time spent":"379.985084ms","remote":"127.0.0.1:35536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:03:32.157164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.716345ms","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-13T20:03:32.157201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.527032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pnxzl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-13T20:03:32.157273Z","caller":"traceutil/trace.go:171","msg":"trace[1301684937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"235.883259ms","start":"2026-07-13T20:03:31.921363Z","end":"2026-07-13T20:03:32.157246Z","steps":["trace[1301684937] 'agreement among raft nodes before linearized reading' (duration: 235.577902ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:03:32.157409Z","caller":"traceutil/trace.go:171","msg":"trace[1297109323] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pnxzl; range_end:; response_count:1; response_revision:587; }","duration":"415.767978ms","start":"2026-07-13T20:03:31.741579Z","end":"2026-07-13T20:03:32.157347Z","steps":["trace[1297109323] 'agreement among raft nodes before linearized reading' (duration: 415.197174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:32.157544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:03:31.741555Z","time spent":"415.978393ms","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-pnxzl\" "} {"level":"info","ts":"2026-07-13T20:03:32.304296Z","caller":"traceutil/trace.go:171","msg":"trace[324157020] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"137.4139ms","start":"2026-07-13T20:03:32.166864Z","end":"2026-07-13T20:03:32.304278Z","steps":["trace[324157020] 'read index received' (duration: 117.671683ms)","trace[324157020] 'applied index is now lower than readState.Index' (duration: 19.740407ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:32.304389Z","caller":"traceutil/trace.go:171","msg":"trace[1900370992] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"142.326292ms","start":"2026-07-13T20:03:32.162048Z","end":"2026-07-13T20:03:32.304374Z","steps":["trace[1900370992] 'process raft request' (duration: 122.534143ms)","trace[1900370992] 'compare' (duration: 19.41574ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:32.304452Z","caller":"traceutil/trace.go:171","msg":"trace[1422688986] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"131.355001ms","start":"2026-07-13T20:03:32.173059Z","end":"2026-07-13T20:03:32.304414Z","steps":["trace[1422688986] 'process raft request' (duration: 131.158116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:32.304561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.669576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:03:32.30486Z","caller":"traceutil/trace.go:171","msg":"trace[102389579] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:589; }","duration":"138.029015ms","start":"2026-07-13T20:03:32.166817Z","end":"2026-07-13T20:03:32.304846Z","steps":["trace[102389579] 'agreement among raft nodes before linearized reading' (duration: 137.521493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:46.970964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.68292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:03:46.971086Z","caller":"traceutil/trace.go:171","msg":"trace[1278407255] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"171.425089ms","start":"2026-07-13T20:03:46.799641Z","end":"2026-07-13T20:03:46.971066Z","steps":["trace[1278407255] 'read index received' (duration: 15.614416ms)","trace[1278407255] 'applied index is now lower than readState.Index' (duration: 155.809173ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:03:46.971181Z","caller":"traceutil/trace.go:171","msg":"trace[711421543] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"259.523222ms","start":"2026-07-13T20:03:46.711646Z","end":"2026-07-13T20:03:46.971169Z","steps":["trace[711421543] 'process raft request' (duration: 103.532274ms)","trace[711421543] 'compare' (duration: 155.476345ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:03:46.971381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.550319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4vzl6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-13T20:03:46.971465Z","caller":"traceutil/trace.go:171","msg":"trace[1827751313] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4vzl6; range_end:; response_count:1; response_revision:749; }","duration":"134.673083ms","start":"2026-07-13T20:03:46.836773Z","end":"2026-07-13T20:03:46.971446Z","steps":["trace[1827751313] 'agreement among raft nodes before linearized reading' (duration: 134.442417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:03:46.971682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.033755ms","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-13T20:03:46.97177Z","caller":"traceutil/trace.go:171","msg":"trace[1095305660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"172.173509ms","start":"2026-07-13T20:03:46.799575Z","end":"2026-07-13T20:03:46.971748Z","steps":["trace[1095305660] 'agreement among raft nodes before linearized reading' (duration: 171.926352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:05:06.500625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.996088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41054e9c-2289-4e03-9baf-9a411fdb0f1c\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-13T20:05:06.500914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.565399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41054e9c-2289-4e03-9baf-9a411fdb0f1c\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-13T20:05:06.500942Z","caller":"traceutil/trace.go:171","msg":"trace[1400215455] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41054e9c-2289-4e03-9baf-9a411fdb0f1c; range_end:; response_count:1; response_revision:1833; }","duration":"114.365227ms","start":"2026-07-13T20:05:06.386558Z","end":"2026-07-13T20:05:06.500923Z","steps":["trace[1400215455] 'range keys from in-memory index tree' (duration: 113.772683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:05:06.500986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.045699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-13T20:05:06.501073Z","caller":"traceutil/trace.go:171","msg":"trace[463077700] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"103.180182ms","start":"2026-07-13T20:05:06.39787Z","end":"2026-07-13T20:05:06.50105Z","steps":["trace[463077700] 'range keys from in-memory index tree' (duration: 102.643829ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:06.501005Z","caller":"traceutil/trace.go:171","msg":"trace[1816438259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41054e9c-2289-4e03-9baf-9a411fdb0f1c; range_end:; response_count:1; response_revision:1833; }","duration":"283.714113ms","start":"2026-07-13T20:05:06.21727Z","end":"2026-07-13T20:05:06.500984Z","steps":["trace[1816438259] 'range keys from in-memory index tree' (duration: 283.18643ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:06.900965Z","caller":"traceutil/trace.go:171","msg":"trace[1158062344] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"130.37806ms","start":"2026-07-13T20:05:06.770549Z","end":"2026-07-13T20:05:06.900927Z","steps":["trace[1158062344] 'process raft request' (duration: 130.193825ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:20.366872Z","caller":"traceutil/trace.go:171","msg":"trace[1555707784] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"106.127745ms","start":"2026-07-13T20:05:20.260722Z","end":"2026-07-13T20:05:20.36685Z","steps":["trace[1555707784] 'process raft request' (duration: 105.994931ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:50.581222Z","caller":"traceutil/trace.go:171","msg":"trace[576268676] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"120.950876ms","start":"2026-07-13T20:05:50.460242Z","end":"2026-07-13T20:05:50.581193Z","steps":["trace[576268676] 'process raft request' (duration: 120.759321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:05:51.202414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.584655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:05:51.202697Z","caller":"traceutil/trace.go:171","msg":"trace[1210391432] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"275.899922ms","start":"2026-07-13T20:05:50.926755Z","end":"2026-07-13T20:05:51.202655Z","steps":["trace[1210391432] 'process raft request' (duration: 125.960928ms)","trace[1210391432] 'compare' (duration: 149.444952ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:05:51.573769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.570201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:05:51.57407Z","caller":"traceutil/trace.go:171","msg":"trace[1510008873] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"367.131004ms","start":"2026-07-13T20:05:51.206894Z","end":"2026-07-13T20:05:51.574025Z","steps":["trace[1510008873] 'process raft request' (duration: 142.136533ms)","trace[1510008873] 'compare' (duration: 224.383106ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:05:51.574065Z","caller":"traceutil/trace.go:171","msg":"trace[344532192] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"324.822712ms","start":"2026-07-13T20:05:51.249217Z","end":"2026-07-13T20:05:51.57404Z","steps":["trace[344532192] 'process raft request' (duration: 324.72871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:05:51.574299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:05:51.249176Z","time spent":"325.069739ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:05:51.574402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:05:51.206875Z","time spent":"367.292038ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:05:51.622018Z","caller":"traceutil/trace.go:171","msg":"trace[756579430] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"196.387885ms","start":"2026-07-13T20:05:51.425601Z","end":"2026-07-13T20:05:51.621989Z","steps":["trace[756579430] 'process raft request' (duration: 196.232872ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:51.770502Z","caller":"traceutil/trace.go:171","msg":"trace[1833182926] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"144.105521ms","start":"2026-07-13T20:05:51.62635Z","end":"2026-07-13T20:05:51.770456Z","steps":["trace[1833182926] 'process raft request' (duration: 49.346542ms)","trace[1833182926] 'compare' (duration: 94.567975ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:05:57.365153Z","caller":"traceutil/trace.go:171","msg":"trace[413692487] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"109.542558ms","start":"2026-07-13T20:05:57.255576Z","end":"2026-07-13T20:05:57.365118Z","steps":["trace[413692487] 'process raft request' (duration: 109.338663ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:57.892809Z","caller":"traceutil/trace.go:171","msg":"trace[2052356122] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"251.345264ms","start":"2026-07-13T20:05:57.641395Z","end":"2026-07-13T20:05:57.892741Z","steps":["trace[2052356122] 'process raft request' (duration: 232.108605ms)","trace[2052356122] 'compare' (duration: 18.963372ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:05:57.892868Z","caller":"traceutil/trace.go:171","msg":"trace[1635336968] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"192.451799ms","start":"2026-07-13T20:05:57.70038Z","end":"2026-07-13T20:05:57.892832Z","steps":["trace[1635336968] 'process raft request' (duration: 192.352686ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:05:57.89287Z","caller":"traceutil/trace.go:171","msg":"trace[2041461882] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"197.601363ms","start":"2026-07-13T20:05:57.695235Z","end":"2026-07-13T20:05:57.892836Z","steps":["trace[2041461882] 'read index received' (duration: 178.302083ms)","trace[2041461882] 'applied index is now lower than readState.Index' (duration: 19.29543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:05:57.893073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.979046ms","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-13T20:05:57.893201Z","caller":"traceutil/trace.go:171","msg":"trace[2070562561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2167; }","duration":"249.17296ms","start":"2026-07-13T20:05:57.644007Z","end":"2026-07-13T20:05:57.89318Z","steps":["trace[2070562561] 'agreement among raft nodes before linearized reading' (duration: 248.937965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:05:57.893644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:05:57.893708Z","caller":"traceutil/trace.go:171","msg":"trace[1488730107] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2168; }","duration":"177.755179ms","start":"2026-07-13T20:05:57.715936Z","end":"2026-07-13T20:05:57.893691Z","steps":["trace[1488730107] 'agreement among raft nodes before linearized reading' (duration: 177.614726ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:07:26.107731Z","caller":"traceutil/trace.go:171","msg":"trace[1535696423] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"119.220052ms","start":"2026-07-13T20:07:25.988486Z","end":"2026-07-13T20:07:26.107706Z","steps":["trace[1535696423] 'process raft request' (duration: 119.078389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:07:26.601838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.817557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:07:26.602093Z","caller":"traceutil/trace.go:171","msg":"trace[367365469] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2950; }","duration":"263.902373ms","start":"2026-07-13T20:07:26.338172Z","end":"2026-07-13T20:07:26.602074Z","steps":["trace[367365469] 'read index received' (duration: 154.914353ms)","trace[367365469] 'applied index is now lower than readState.Index' (duration: 108.98643ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:07:26.602124Z","caller":"traceutil/trace.go:171","msg":"trace[693291818] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"296.154256ms","start":"2026-07-13T20:07:26.30594Z","end":"2026-07-13T20:07:26.602094Z","steps":["trace[693291818] 'process raft request' (duration: 296.020563ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:07:26.602114Z","caller":"traceutil/trace.go:171","msg":"trace[1787906920] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"296.164286ms","start":"2026-07-13T20:07:26.305905Z","end":"2026-07-13T20:07:26.602069Z","steps":["trace[1787906920] 'process raft request' (duration: 186.986672ms)","trace[1787906920] 'compare' (duration: 108.484391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:07:26.602306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.085047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:07:26.602504Z","caller":"traceutil/trace.go:171","msg":"trace[263681455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2841; }","duration":"264.345022ms","start":"2026-07-13T20:07:26.338137Z","end":"2026-07-13T20:07:26.602482Z","steps":["trace[263681455] 'agreement among raft nodes before linearized reading' (duration: 264.058466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:07:26.602398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.402733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-13T20:07:26.60263Z","caller":"traceutil/trace.go:171","msg":"trace[1911439138] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2841; }","duration":"176.6624ms","start":"2026-07-13T20:07:26.425948Z","end":"2026-07-13T20:07:26.60261Z","steps":["trace[1911439138] 'agreement among raft nodes before linearized reading' (duration: 176.266771ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:09:53.080959Z","caller":"traceutil/trace.go:171","msg":"trace[794688840] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"258.739683ms","start":"2026-07-13T20:09:52.822189Z","end":"2026-07-13T20:09:53.080928Z","steps":["trace[794688840] 'process raft request' (duration: 258.458725ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:19.426611Z","caller":"traceutil/trace.go:171","msg":"trace[1194386400] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"158.643715ms","start":"2026-07-13T20:11:19.267941Z","end":"2026-07-13T20:11:19.426585Z","steps":["trace[1194386400] 'process raft request' (duration: 114.926407ms)","trace[1194386400] 'compare' (duration: 43.430501ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:11:19.469481Z","caller":"traceutil/trace.go:171","msg":"trace[747895435] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"100.390112ms","start":"2026-07-13T20:11:19.369061Z","end":"2026-07-13T20:11:19.469451Z","steps":["trace[747895435] 'process raft request' (duration: 99.945221ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:19.469782Z","caller":"traceutil/trace.go:171","msg":"trace[1606474642] linearizableReadLoop","detail":"{readStateIndex:5201; appliedIndex:5199; }","duration":"109.799644ms","start":"2026-07-13T20:11:19.359969Z","end":"2026-07-13T20:11:19.469768Z","steps":["trace[1606474642] 'read index received' (duration: 22.917981ms)","trace[1606474642] 'applied index is now lower than readState.Index' (duration: 86.876262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:11:19.470061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.07839ms","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-13T20:11:19.470104Z","caller":"traceutil/trace.go:171","msg":"trace[1904036456] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5010; }","duration":"110.136961ms","start":"2026-07-13T20:11:19.359956Z","end":"2026-07-13T20:11:19.470093Z","steps":["trace[1904036456] 'agreement among raft nodes before linearized reading' (duration: 109.952797ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:31.132979Z","caller":"traceutil/trace.go:171","msg":"trace[1117780414] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"136.042015ms","start":"2026-07-13T20:11:30.996906Z","end":"2026-07-13T20:11:31.132948Z","steps":["trace[1117780414] 'process raft request' (duration: 135.809549ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.63091Z","caller":"traceutil/trace.go:171","msg":"trace[881427119] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"276.052195ms","start":"2026-07-13T20:11:38.354794Z","end":"2026-07-13T20:11:38.630847Z","steps":["trace[881427119] 'process raft request' (duration: 177.409104ms)","trace[881427119] 'compare' (duration: 98.371924ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:11:38.631032Z","caller":"traceutil/trace.go:171","msg":"trace[1527737477] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5614; }","duration":"251.613437ms","start":"2026-07-13T20:11:38.379381Z","end":"2026-07-13T20:11:38.630995Z","steps":["trace[1527737477] 'read index received' (duration: 152.815053ms)","trace[1527737477] 'applied index is now lower than readState.Index' (duration: 98.795024ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:11:38.631348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.951656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9a3877c-48c6-41f5-9a98-6a09e4b5f23d\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-07-13T20:11:38.631404Z","caller":"traceutil/trace.go:171","msg":"trace[1513323658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9a3877c-48c6-41f5-9a98-6a09e4b5f23d; range_end:; response_count:1; response_revision:5412; }","duration":"252.052529ms","start":"2026-07-13T20:11:38.379335Z","end":"2026-07-13T20:11:38.631388Z","steps":["trace[1513323658] 'agreement among raft nodes before linearized reading' (duration: 251.776672ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.786304Z","caller":"traceutil/trace.go:171","msg":"trace[517115745] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"148.960489ms","start":"2026-07-13T20:11:38.637307Z","end":"2026-07-13T20:11:38.786268Z","steps":["trace[517115745] 'process raft request' (duration: 138.429832ms)","trace[517115745] 'compare' (duration: 10.320312ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:11:38.786394Z","caller":"traceutil/trace.go:171","msg":"trace[1146087994] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"148.931969ms","start":"2026-07-13T20:11:38.637435Z","end":"2026-07-13T20:11:38.786367Z","steps":["trace[1146087994] 'process raft request' (duration: 148.774225ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.786396Z","caller":"traceutil/trace.go:171","msg":"trace[214920101] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"148.882598ms","start":"2026-07-13T20:11:38.637478Z","end":"2026-07-13T20:11:38.786361Z","steps":["trace[214920101] 'process raft request' (duration: 148.788285ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.786665Z","caller":"traceutil/trace.go:171","msg":"trace[2031783159] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"149.102033ms","start":"2026-07-13T20:11:38.637539Z","end":"2026-07-13T20:11:38.786641Z","steps":["trace[2031783159] 'process raft request' (duration: 148.783736ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.786712Z","caller":"traceutil/trace.go:171","msg":"trace[1753853799] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"146.438197ms","start":"2026-07-13T20:11:38.640246Z","end":"2026-07-13T20:11:38.786684Z","steps":["trace[1753853799] 'process raft request' (duration: 146.291034ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:11:38.787287Z","caller":"traceutil/trace.go:171","msg":"trace[728345328] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5617; }","duration":"101.482519ms","start":"2026-07-13T20:11:38.68576Z","end":"2026-07-13T20:11:38.787242Z","steps":["trace[728345328] 'read index received' (duration: 89.965017ms)","trace[728345328] 'applied index is now lower than readState.Index' (duration: 11.515822ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:11:38.787603Z","caller":"traceutil/trace.go:171","msg":"trace[651091553] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"144.969161ms","start":"2026-07-13T20:11:38.642573Z","end":"2026-07-13T20:11:38.787542Z","steps":["trace[651091553] 'process raft request' (duration: 144.071099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:11:38.787899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.064984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-13T20:11:38.789219Z","caller":"traceutil/trace.go:171","msg":"trace[1161560305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5419; }","duration":"103.464858ms","start":"2026-07-13T20:11:38.685713Z","end":"2026-07-13T20:11:38.789178Z","steps":["trace[1161560305] 'agreement among raft nodes before linearized reading' (duration: 101.967081ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:12:50.959167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-07-13T20:12:51.0293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"68.522539ms","hash":3639705079,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-13T20:12:51.029386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3639705079,"revision":2975,"compact-revision":-1} {"level":"info","ts":"2026-07-13T20:17:48.389265Z","caller":"traceutil/trace.go:171","msg":"trace[1640813081] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"107.01506ms","start":"2026-07-13T20:17:48.282214Z","end":"2026-07-13T20:17:48.389229Z","steps":["trace[1640813081] 'process raft request' (duration: 106.850426ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:17:50.965626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6077} {"level":"info","ts":"2026-07-13T20:17:51.057865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6077,"took":"90.130302ms","hash":3485159359,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":23486464,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-13T20:17:51.057916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485159359,"revision":6077,"compact-revision":2975} {"level":"info","ts":"2026-07-13T20:18:01.026471Z","caller":"traceutil/trace.go:171","msg":"trace[1705767649] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"280.232413ms","start":"2026-07-13T20:18:00.746206Z","end":"2026-07-13T20:18:01.026438Z","steps":["trace[1705767649] 'process raft request' (duration: 224.096824ms)","trace[1705767649] 'compare' (duration: 55.998806ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:18:01.043502Z","caller":"traceutil/trace.go:171","msg":"trace[2068186540] linearizableReadLoop","detail":"{readStateIndex:8622; appliedIndex:8620; }","duration":"110.413332ms","start":"2026-07-13T20:18:00.933064Z","end":"2026-07-13T20:18:01.043477Z","steps":["trace[2068186540] 'read index received' (duration: 37.354324ms)","trace[2068186540] 'applied index is now lower than readState.Index' (duration: 73.057338ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:18:01.043615Z","caller":"traceutil/trace.go:171","msg":"trace[2141869929] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"263.425236ms","start":"2026-07-13T20:18:00.780141Z","end":"2026-07-13T20:18:01.043566Z","steps":["trace[2141869929] 'process raft request' (duration: 261.91398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:18:01.043707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.620757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:18:01.043746Z","caller":"traceutil/trace.go:171","msg":"trace[643041914] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8323; }","duration":"110.726119ms","start":"2026-07-13T20:18:00.933008Z","end":"2026-07-13T20:18:01.043734Z","steps":["trace[643041914] 'agreement among raft nodes before linearized reading' (duration: 110.550765ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:18:01.832112Z","caller":"traceutil/trace.go:171","msg":"trace[1847207097] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"101.689062ms","start":"2026-07-13T20:18:01.730393Z","end":"2026-07-13T20:18:01.832082Z","steps":["trace[1847207097] 'process raft request' (duration: 74.447202ms)","trace[1847207097] 'compare' (duration: 26.983304ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:18:25.792871Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000226f.wal"} {"level":"info","ts":"2026-07-13T20:18:29.133674Z","caller":"traceutil/trace.go:171","msg":"trace[2079479141] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8830; }","duration":"385.328747ms","start":"2026-07-13T20:18:28.748319Z","end":"2026-07-13T20:18:29.133648Z","steps":["trace[2079479141] 'read index received' (duration: 385.172913ms)","trace[2079479141] 'applied index is now lower than readState.Index' (duration: 154.564µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:18:29.133834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.485001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:18:29.133875Z","caller":"traceutil/trace.go:171","msg":"trace[1647068891] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8525; }","duration":"385.588323ms","start":"2026-07-13T20:18:28.748276Z","end":"2026-07-13T20:18:29.133864Z","steps":["trace[1647068891] 'agreement among raft nodes before linearized reading' (duration: 385.475291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:18:29.133912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:18:28.748256Z","time spent":"385.645864ms","remote":"127.0.0.1:36536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:18:29.134332Z","caller":"traceutil/trace.go:171","msg":"trace[1894187995] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"403.332889ms","start":"2026-07-13T20:18:28.730988Z","end":"2026-07-13T20:18:29.134321Z","steps":["trace[1894187995] 'process raft request' (duration: 402.511799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:18:29.13442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:18:28.730966Z","time spent":"403.40107ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:18:29.150751Z","caller":"traceutil/trace.go:171","msg":"trace[1024132458] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"293.770407ms","start":"2026-07-13T20:18:28.85697Z","end":"2026-07-13T20:18:29.150741Z","steps":["trace[1024132458] 'process raft request' (duration: 293.534601ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:18:41.894258Z","caller":"traceutil/trace.go:171","msg":"trace[1798082851] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"144.889271ms","start":"2026-07-13T20:18:41.749351Z","end":"2026-07-13T20:18:41.89424Z","steps":["trace[1798082851] 'process raft request' (duration: 144.778058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:19:43.00827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.455144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:19:43.008339Z","caller":"traceutil/trace.go:171","msg":"trace[1190739565] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:9276; }","duration":"150.603067ms","start":"2026-07-13T20:19:42.857723Z","end":"2026-07-13T20:19:43.008326Z","steps":["trace[1190739565] 'count revisions from in-memory index tree' (duration: 150.389151ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:20:33.627343Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T20:20:33.638471Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-13T20:20:33.638592Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-13T20:22:50.972013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8271} {"level":"info","ts":"2026-07-13T20:22:51.051051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8271,"took":"75.902042ms","hash":3865115245,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":26017792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-13T20:22:51.051107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3865115245,"revision":8271,"compact-revision":6077} {"level":"info","ts":"2026-07-13T20:23:34.533638Z","caller":"traceutil/trace.go:171","msg":"trace[643540152] transaction","detail":"{read_only:false; response_revision:11195; number_of_response:1; }","duration":"274.205845ms","start":"2026-07-13T20:23:34.259401Z","end":"2026-07-13T20:23:34.533607Z","steps":["trace[643540152] 'process raft request' (duration: 254.364223ms)","trace[643540152] 'compare' (duration: 19.663117ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:23:34.533946Z","caller":"traceutil/trace.go:171","msg":"trace[1850122710] linearizableReadLoop","detail":"{readStateIndex:11583; appliedIndex:11580; }","duration":"195.981776ms","start":"2026-07-13T20:23:34.33794Z","end":"2026-07-13T20:23:34.533922Z","steps":["trace[1850122710] 'read index received' (duration: 175.833737ms)","trace[1850122710] 'applied index is now lower than readState.Index' (duration: 20.146599ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:23:34.53403Z","caller":"traceutil/trace.go:171","msg":"trace[293590629] transaction","detail":"{read_only:false; response_revision:11196; number_of_response:1; }","duration":"274.373768ms","start":"2026-07-13T20:23:34.259642Z","end":"2026-07-13T20:23:34.534016Z","steps":["trace[293590629] 'process raft request' (duration: 273.917727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:23:34.534077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.12467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:23:34.534113Z","caller":"traceutil/trace.go:171","msg":"trace[1401787373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11197; }","duration":"196.178311ms","start":"2026-07-13T20:23:34.337924Z","end":"2026-07-13T20:23:34.534102Z","steps":["trace[1401787373] 'agreement among raft nodes before linearized reading' (duration: 196.092659ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:23:34.534119Z","caller":"traceutil/trace.go:171","msg":"trace[1968314036] transaction","detail":"{read_only:false; response_revision:11197; number_of_response:1; }","duration":"274.092741ms","start":"2026-07-13T20:23:34.259996Z","end":"2026-07-13T20:23:34.534089Z","steps":["trace[1968314036] 'process raft request' (duration: 273.812754ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:23:35.868152Z","caller":"traceutil/trace.go:171","msg":"trace[1466975160] transaction","detail":"{read_only:false; response_revision:11202; number_of_response:1; }","duration":"209.682948ms","start":"2026-07-13T20:23:35.658447Z","end":"2026-07-13T20:23:35.86813Z","steps":["trace[1466975160] 'process raft request' (duration: 209.541305ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:23:36.056134Z","caller":"traceutil/trace.go:171","msg":"trace[1960809293] transaction","detail":"{read_only:false; response_revision:11203; number_of_response:1; }","duration":"395.97086ms","start":"2026-07-13T20:23:35.660106Z","end":"2026-07-13T20:23:36.056076Z","steps":["trace[1960809293] 'process raft request' (duration: 327.045537ms)","trace[1960809293] 'compare' (duration: 68.496943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:23:36.05631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:23:35.660097Z","time spent":"396.103183ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:27:50.979728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10756} {"level":"info","ts":"2026-07-13T20:27:51.057181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10756,"took":"74.561181ms","hash":1597714570,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-13T20:27:51.057236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1597714570,"revision":10756,"compact-revision":8271} {"level":"info","ts":"2026-07-13T20:30:01.307982Z","caller":"traceutil/trace.go:171","msg":"trace[1206141593] transaction","detail":"{read_only:false; response_revision:13783; number_of_response:1; }","duration":"131.638998ms","start":"2026-07-13T20:30:01.176323Z","end":"2026-07-13T20:30:01.307962Z","steps":["trace[1206141593] 'process raft request' (duration: 131.492554ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:05.581554Z","caller":"traceutil/trace.go:171","msg":"trace[939925801] linearizableReadLoop","detail":"{readStateIndex:15651; appliedIndex:15650; }","duration":"246.061229ms","start":"2026-07-13T20:32:05.335471Z","end":"2026-07-13T20:32:05.581533Z","steps":["trace[939925801] 'read index received' (duration: 245.877825ms)","trace[939925801] 'applied index is now lower than readState.Index' (duration: 182.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:05.582016Z","caller":"traceutil/trace.go:171","msg":"trace[744013212] transaction","detail":"{read_only:false; response_revision:15135; number_of_response:1; }","duration":"286.349527ms","start":"2026-07-13T20:32:05.295652Z","end":"2026-07-13T20:32:05.582001Z","steps":["trace[744013212] 'process raft request' (duration: 285.761243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:05.582311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.827417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:05.582347Z","caller":"traceutil/trace.go:171","msg":"trace[1783672537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15135; }","duration":"246.895788ms","start":"2026-07-13T20:32:05.335441Z","end":"2026-07-13T20:32:05.582337Z","steps":["trace[1783672537] 'agreement among raft nodes before linearized reading' (duration: 246.827077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:05.582628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.208608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tznqz\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-07-13T20:32:05.582703Z","caller":"traceutil/trace.go:171","msg":"trace[1912472412] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tznqz; range_end:; response_count:1; response_revision:15135; }","duration":"124.3194ms","start":"2026-07-13T20:32:05.458368Z","end":"2026-07-13T20:32:05.582687Z","steps":["trace[1912472412] 'agreement among raft nodes before linearized reading' (duration: 124.123265ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:50.986013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12841} {"level":"info","ts":"2026-07-13T20:32:51.061651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12841,"took":"73.086876ms","hash":2947739335,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T20:32:51.061725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2947739335,"revision":12841,"compact-revision":10756} {"level":"info","ts":"2026-07-13T20:37:49.508083Z","caller":"traceutil/trace.go:171","msg":"trace[607169143] transaction","detail":"{read_only:false; response_revision:17731; number_of_response:1; }","duration":"246.333756ms","start":"2026-07-13T20:37:49.261716Z","end":"2026-07-13T20:37:49.50805Z","steps":["trace[607169143] 'process raft request' (duration: 240.373091ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:37:49.539603Z","caller":"traceutil/trace.go:171","msg":"trace[738952867] linearizableReadLoop","detail":"{readStateIndex:18333; appliedIndex:18331; }","duration":"200.716009ms","start":"2026-07-13T20:37:49.33886Z","end":"2026-07-13T20:37:49.539576Z","steps":["trace[738952867] 'read index received' (duration: 163.223489ms)","trace[738952867] 'applied index is now lower than readState.Index' (duration: 37.490999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:37:49.539763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.896864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:37:49.539973Z","caller":"traceutil/trace.go:171","msg":"trace[1664938199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17731; }","duration":"201.179461ms","start":"2026-07-13T20:37:49.338782Z","end":"2026-07-13T20:37:49.539962Z","steps":["trace[1664938199] 'agreement among raft nodes before linearized reading' (duration: 200.911863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:37:49.540445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.77713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tznqz\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-07-13T20:37:49.540476Z","caller":"traceutil/trace.go:171","msg":"trace[394317702] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tznqz; range_end:; response_count:1; response_revision:17731; }","duration":"134.853282ms","start":"2026-07-13T20:37:49.405616Z","end":"2026-07-13T20:37:49.540469Z","steps":["trace[394317702] 'agreement among raft nodes before linearized reading' (duration: 134.740039ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:37:51.022485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15434} {"level":"info","ts":"2026-07-13T20:37:51.7056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15434,"took":"680.234641ms","hash":3955419674,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":32792576,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-13T20:37:51.705643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3955419674,"revision":15434,"compact-revision":12841} {"level":"info","ts":"2026-07-13T20:41:13.985092Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T20:41:13.990219Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-13T20:41:13.990447Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-13T20:42:51.034811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17747} {"level":"info","ts":"2026-07-13T20:42:51.13665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17747,"took":"96.480404ms","hash":2432231095,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":34439168,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-13T20:42:51.136719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2432231095,"revision":17747,"compact-revision":15434} {"level":"info","ts":"2026-07-13T20:43:41.785056Z","caller":"traceutil/trace.go:171","msg":"trace[1930081404] transaction","detail":"{read_only:false; response_revision:20393; number_of_response:1; }","duration":"144.190482ms","start":"2026-07-13T20:43:41.640824Z","end":"2026-07-13T20:43:41.785014Z","steps":["trace[1930081404] 'process raft request' (duration: 143.818063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:43:41.968611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.242584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:43:41.968712Z","caller":"traceutil/trace.go:171","msg":"trace[92138117] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20393; }","duration":"107.423558ms","start":"2026-07-13T20:43:41.861267Z","end":"2026-07-13T20:43:41.968691Z","steps":["trace[92138117] 'count revisions from in-memory index tree' (duration: 107.145271ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:43:42.860866Z","caller":"traceutil/trace.go:171","msg":"trace[2113700043] transaction","detail":"{read_only:false; response_revision:20396; number_of_response:1; }","duration":"105.889992ms","start":"2026-07-13T20:43:42.754942Z","end":"2026-07-13T20:43:42.860832Z","steps":["trace[2113700043] 'process raft request' (duration: 57.200409ms)","trace[2113700043] 'compare' (duration: 48.550399ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:43:42.99793Z","caller":"traceutil/trace.go:171","msg":"trace[1371197323] transaction","detail":"{read_only:false; response_revision:20397; number_of_response:1; }","duration":"118.338244ms","start":"2026-07-13T20:43:42.879565Z","end":"2026-07-13T20:43:42.997903Z","steps":["trace[1371197323] 'process raft request' (duration: 116.854118ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:43:43.180062Z","caller":"traceutil/trace.go:171","msg":"trace[1864924029] linearizableReadLoop","detail":"{readStateIndex:21088; appliedIndex:21086; }","duration":"147.860411ms","start":"2026-07-13T20:43:43.032164Z","end":"2026-07-13T20:43:43.180025Z","steps":["trace[1864924029] 'read index received' (duration: 147.580063ms)","trace[1864924029] 'applied index is now lower than readState.Index' (duration: 279.068µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:43:43.180094Z","caller":"traceutil/trace.go:171","msg":"trace[905765623] transaction","detail":"{read_only:false; response_revision:20398; number_of_response:1; }","duration":"278.159245ms","start":"2026-07-13T20:43:42.901891Z","end":"2026-07-13T20:43:43.18005Z","steps":["trace[905765623] 'process raft request' (duration: 277.891578ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:43:43.180179Z","caller":"traceutil/trace.go:171","msg":"trace[752207481] transaction","detail":"{read_only:false; response_revision:20399; number_of_response:1; }","duration":"278.119924ms","start":"2026-07-13T20:43:42.902014Z","end":"2026-07-13T20:43:43.180134Z","steps":["trace[752207481] 'process raft request' (duration: 277.968111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:43:43.180308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.113727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:43:43.180445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.997295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-13T20:43:43.18047Z","caller":"traceutil/trace.go:171","msg":"trace[617207221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20399; }","duration":"118.060226ms","start":"2026-07-13T20:43:43.062401Z","end":"2026-07-13T20:43:43.180461Z","steps":["trace[617207221] 'agreement among raft nodes before linearized reading' (duration: 117.933493ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:43:43.180484Z","caller":"traceutil/trace.go:171","msg":"trace[637787573] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:20399; }","duration":"148.330842ms","start":"2026-07-13T20:43:43.032119Z","end":"2026-07-13T20:43:43.18045Z","steps":["trace[637787573] 'agreement among raft nodes before linearized reading' (duration: 148.110747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:43:43.403957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.378799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T20:43:43.404056Z","caller":"traceutil/trace.go:171","msg":"trace[1847078716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20400; }","duration":"117.523103ms","start":"2026-07-13T20:43:43.286512Z","end":"2026-07-13T20:43:43.404035Z","steps":["trace[1847078716] 'range keys from in-memory index tree' (duration: 117.143604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:47:44.803712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.21031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-13T20:47:44.803931Z","caller":"traceutil/trace.go:171","msg":"trace[724630559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22893; }","duration":"167.487186ms","start":"2026-07-13T20:47:44.636422Z","end":"2026-07-13T20:47:44.803909Z","steps":["trace[724630559] 'range keys from in-memory index tree' (duration: 166.974984ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:47:51.040983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20126} {"level":"info","ts":"2026-07-13T20:47:51.148078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20126,"took":"103.810931ms","hash":2459437441,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":43016192,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-13T20:47:51.148133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2459437441,"revision":20126,"compact-revision":17747} {"level":"info","ts":"2026-07-13T20:50:56.533382Z","caller":"traceutil/trace.go:171","msg":"trace[786319505] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"107.583973ms","start":"2026-07-13T20:50:56.425778Z","end":"2026-07-13T20:50:56.533362Z","steps":["trace[786319505] 'process raft request' (duration: 107.413559ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:56.542368Z","caller":"traceutil/trace.go:171","msg":"trace[1493105847] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"111.62514ms","start":"2026-07-13T20:50:56.430717Z","end":"2026-07-13T20:50:56.542342Z","steps":["trace[1493105847] 'process raft request' (duration: 111.076946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:56.908227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.251027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-13T20:50:56.908319Z","caller":"traceutil/trace.go:171","msg":"trace[1841418566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24884; }","duration":"308.361329ms","start":"2026-07-13T20:50:56.599937Z","end":"2026-07-13T20:50:56.908298Z","steps":["trace[1841418566] 'range keys from in-memory index tree' (duration: 307.98748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:56.908362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:50:56.599812Z","time spent":"308.540774ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-13T20:50:56.908758Z","caller":"traceutil/trace.go:171","msg":"trace[663262894] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"258.913137ms","start":"2026-07-13T20:50:56.649804Z","end":"2026-07-13T20:50:56.908717Z","steps":["trace[663262894] 'process raft request' (duration: 164.351382ms)","trace[663262894] 'compare' (duration: 94.119055ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:50:57.032393Z","caller":"traceutil/trace.go:171","msg":"trace[2070025037] linearizableReadLoop","detail":"{readStateIndex:25699; appliedIndex:25696; }","duration":"268.632824ms","start":"2026-07-13T20:50:56.763741Z","end":"2026-07-13T20:50:57.032373Z","steps":["trace[2070025037] 'read index received' (duration: 50.427435ms)","trace[2070025037] 'applied index is now lower than readState.Index' (duration: 218.204579ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:50:57.032421Z","caller":"traceutil/trace.go:171","msg":"trace[1683576713] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"338.92015ms","start":"2026-07-13T20:50:56.693465Z","end":"2026-07-13T20:50:57.032386Z","steps":["trace[1683576713] 'process raft request' (duration: 329.8636ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:57.032509Z","caller":"traceutil/trace.go:171","msg":"trace[554839175] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"261.127772ms","start":"2026-07-13T20:50:56.77137Z","end":"2026-07-13T20:50:57.032497Z","steps":["trace[554839175] 'process raft request' (duration: 260.952518ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:57.032476Z","caller":"traceutil/trace.go:171","msg":"trace[1671929251] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"330.594608ms","start":"2026-07-13T20:50:56.701832Z","end":"2026-07-13T20:50:57.032426Z","steps":["trace[1671929251] 'process raft request' (duration: 330.463325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:57.03259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:50:56.693444Z","time spent":"339.063324ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:50:57.032677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:50:56.701807Z","time spent":"330.758713ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:50:57.032724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.672776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:50:57.032763Z","caller":"traceutil/trace.go:171","msg":"trace[1028365642] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24889; }","duration":"266.749868ms","start":"2026-07-13T20:50:56.766001Z","end":"2026-07-13T20:50:57.032751Z","steps":["trace[1028365642] 'agreement among raft nodes before linearized reading' (duration: 266.643216ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:57.032766Z","caller":"traceutil/trace.go:171","msg":"trace[1350284601] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"119.42187ms","start":"2026-07-13T20:50:56.913336Z","end":"2026-07-13T20:50:57.032757Z","steps":["trace[1350284601] 'process raft request' (duration: 119.018111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:57.032907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.168437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:50:57.032926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.909185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:50:57.032951Z","caller":"traceutil/trace.go:171","msg":"trace[1097041090] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24889; }","duration":"206.969837ms","start":"2026-07-13T20:50:56.825973Z","end":"2026-07-13T20:50:57.032942Z","steps":["trace[1097041090] 'agreement among raft nodes before linearized reading' (duration: 206.923646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:57.032955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.020819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:50:57.03293Z","caller":"traceutil/trace.go:171","msg":"trace[2009907850] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24889; }","duration":"269.231148ms","start":"2026-07-13T20:50:56.763692Z","end":"2026-07-13T20:50:57.032923Z","steps":["trace[2009907850] 'agreement among raft nodes before linearized reading' (duration: 268.920131ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:57.033042Z","caller":"traceutil/trace.go:171","msg":"trace[1804179616] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24889; }","duration":"135.172323ms","start":"2026-07-13T20:50:56.897848Z","end":"2026-07-13T20:50:57.033021Z","steps":["trace[1804179616] 'agreement among raft nodes before linearized reading' (duration: 135.039519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:58.491921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.98053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:50:58.492182Z","caller":"traceutil/trace.go:171","msg":"trace[192694908] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25709; }","duration":"210.645935ms","start":"2026-07-13T20:50:58.281519Z","end":"2026-07-13T20:50:58.492165Z","steps":["trace[192694908] 'read index received' (duration: 42.306657ms)","trace[192694908] 'applied index is now lower than readState.Index' (duration: 168.337918ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:50:58.492307Z","caller":"traceutil/trace.go:171","msg":"trace[2104854001] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"271.300758ms","start":"2026-07-13T20:50:58.220992Z","end":"2026-07-13T20:50:58.492293Z","steps":["trace[2104854001] 'process raft request' (duration: 102.856608ms)","trace[2104854001] 'compare' (duration: 167.856846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:50:58.492377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.813269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:50:58.492396Z","caller":"traceutil/trace.go:171","msg":"trace[1604953447] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"235.447728ms","start":"2026-07-13T20:50:58.256901Z","end":"2026-07-13T20:50:58.492349Z","steps":["trace[1604953447] 'process raft request' (duration: 235.156181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:58.492487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.287007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:50:58.492544Z","caller":"traceutil/trace.go:171","msg":"trace[2016937818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24900; }","duration":"154.385769ms","start":"2026-07-13T20:50:58.338149Z","end":"2026-07-13T20:50:58.492535Z","steps":["trace[2016937818] 'agreement among raft nodes before linearized reading' (duration: 154.303137ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:58.492585Z","caller":"traceutil/trace.go:171","msg":"trace[1579062846] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24900; }","duration":"211.054365ms","start":"2026-07-13T20:50:58.281515Z","end":"2026-07-13T20:50:58.492569Z","steps":["trace[1579062846] 'agreement among raft nodes before linearized reading' (duration: 210.785178ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:50:58.49258Z","caller":"traceutil/trace.go:171","msg":"trace[1234597915] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"183.425275ms","start":"2026-07-13T20:50:58.30913Z","end":"2026-07-13T20:50:58.492555Z","steps":["trace[1234597915] 'process raft request' (duration: 182.983414ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:03.592893Z","caller":"traceutil/trace.go:171","msg":"trace[408133566] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"103.616016ms","start":"2026-07-13T20:51:03.489225Z","end":"2026-07-13T20:51:03.592841Z","steps":["trace[408133566] 'process raft request' (duration: 103.499544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.215891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.122576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:04.216107Z","caller":"traceutil/trace.go:171","msg":"trace[305360589] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25761; }","duration":"522.303424ms","start":"2026-07-13T20:51:03.693791Z","end":"2026-07-13T20:51:04.216095Z","steps":["trace[305360589] 'read index received' (duration: 7.900461ms)","trace[305360589] 'applied index is now lower than readState.Index' (duration: 514.401973ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:04.216288Z","caller":"traceutil/trace.go:171","msg":"trace[846780359] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"719.593526ms","start":"2026-07-13T20:51:03.496664Z","end":"2026-07-13T20:51:04.216257Z","steps":["trace[846780359] 'process raft request' (duration: 205.021939ms)","trace[846780359] 'compare' (duration: 513.116392ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:04.216314Z","caller":"traceutil/trace.go:171","msg":"trace[316610190] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"523.253747ms","start":"2026-07-13T20:51:03.693029Z","end":"2026-07-13T20:51:04.216283Z","steps":["trace[316610190] 'process raft request' (duration: 522.94352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.216432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.496643Z","time spent":"719.711539ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:04.216443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.633632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:04.216513Z","caller":"traceutil/trace.go:171","msg":"trace[2038593462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24950; }","duration":"522.736314ms","start":"2026-07-13T20:51:03.69376Z","end":"2026-07-13T20:51:04.216497Z","steps":["trace[2038593462] 'agreement among raft nodes before linearized reading' (duration: 522.622112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.216499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.693004Z","time spent":"523.382471ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:04.216559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.693749Z","time spent":"522.802116ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:51:04.525345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.531514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:04.525519Z","caller":"traceutil/trace.go:171","msg":"trace[2059635571] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"476.01112ms","start":"2026-07-13T20:51:04.049466Z","end":"2026-07-13T20:51:04.525477Z","steps":["trace[2059635571] 'process raft request' (duration: 344.26952ms)","trace[2059635571] 'compare' (duration: 131.233497ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:04.525724Z","caller":"traceutil/trace.go:171","msg":"trace[904304508] linearizableReadLoop","detail":"{readStateIndex:25764; appliedIndex:25763; }","duration":"309.329031ms","start":"2026-07-13T20:51:04.216378Z","end":"2026-07-13T20:51:04.525707Z","steps":["trace[904304508] 'read index received' (duration: 177.343896ms)","trace[904304508] 'applied index is now lower than readState.Index' (duration: 131.983895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:04.525729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.049448Z","time spent":"476.176464ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:04.525876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.827861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-13T20:51:04.525865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.300947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-13T20:51:04.525911Z","caller":"traceutil/trace.go:171","msg":"trace[1990800973] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24951; }","duration":"378.922383ms","start":"2026-07-13T20:51:04.14698Z","end":"2026-07-13T20:51:04.525902Z","steps":["trace[1990800973] 'agreement among raft nodes before linearized reading' (duration: 378.80529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.525884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.540628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-07-13T20:51:04.525944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.146966Z","time spent":"378.969994ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:51:04.525981Z","caller":"traceutil/trace.go:171","msg":"trace[1407267042] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24951; }","duration":"659.682551ms","start":"2026-07-13T20:51:03.866281Z","end":"2026-07-13T20:51:04.525963Z","steps":["trace[1407267042] 'agreement among raft nodes before linearized reading' (duration: 659.460495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.525971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.07253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:04.526009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.109397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-13T20:51:04.526029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.86626Z","time spent":"659.759802ms","remote":"127.0.0.1:35274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-07-13T20:51:04.526036Z","caller":"traceutil/trace.go:171","msg":"trace[2008809147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24951; }","duration":"708.163178ms","start":"2026-07-13T20:51:03.817867Z","end":"2026-07-13T20:51:04.52603Z","steps":["trace[2008809147] 'agreement among raft nodes before linearized reading' (duration: 708.094076ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.526044Z","caller":"traceutil/trace.go:171","msg":"trace[2146656053] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24951; }","duration":"779.181514ms","start":"2026-07-13T20:51:03.746847Z","end":"2026-07-13T20:51:04.526028Z","steps":["trace[2146656053] 'agreement among raft nodes before linearized reading' (duration: 779.00857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.52602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.280046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-13T20:51:04.526138Z","caller":"traceutil/trace.go:171","msg":"trace[1282976131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24951; }","duration":"541.43498ms","start":"2026-07-13T20:51:03.984676Z","end":"2026-07-13T20:51:04.526111Z","steps":["trace[1282976131] 'agreement among raft nodes before linearized reading' (duration: 541.096762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.526104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.957069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"warn","ts":"2026-07-13T20:51:04.526071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.817852Z","time spent":"708.21178ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T20:51:04.526189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.984665Z","time spent":"541.511661ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T20:51:04.526207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.616999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-13T20:51:04.526222Z","caller":"traceutil/trace.go:171","msg":"trace[501042424] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:24951; }","duration":"770.136133ms","start":"2026-07-13T20:51:03.756067Z","end":"2026-07-13T20:51:04.526203Z","steps":["trace[501042424] 'agreement among raft nodes before linearized reading' (duration: 769.750944ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.526256Z","caller":"traceutil/trace.go:171","msg":"trace[2136053954] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24951; }","duration":"799.66187ms","start":"2026-07-13T20:51:03.726568Z","end":"2026-07-13T20:51:04.52623Z","steps":["trace[2136053954] 'agreement among raft nodes before linearized reading' (duration: 799.528747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.526069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.79291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"warn","ts":"2026-07-13T20:51:04.526281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.72656Z","time spent":"799.714712ms","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-07-13T20:51:04.526293Z","caller":"traceutil/trace.go:171","msg":"trace[841355417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24951; }","duration":"692.025756ms","start":"2026-07-13T20:51:03.834258Z","end":"2026-07-13T20:51:04.526284Z","steps":["trace[841355417] 'agreement among raft nodes before linearized reading' (duration: 691.622996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.526287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.756044Z","time spent":"770.234375ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-07-13T20:51:04.526084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.74684Z","time spent":"779.236255ms","remote":"127.0.0.1:41310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:51:04.526323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:03.834253Z","time spent":"692.062157ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-07-13T20:51:04.525933Z","caller":"traceutil/trace.go:171","msg":"trace[1169069072] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:24951; }","duration":"497.42136ms","start":"2026-07-13T20:51:04.028498Z","end":"2026-07-13T20:51:04.525919Z","steps":["trace[1169069072] 'agreement among raft nodes before linearized reading' (duration: 497.258946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.526453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.028477Z","time spent":"497.968874ms","remote":"127.0.0.1:35224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-07-13T20:51:04.599992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.033418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:04.600034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.960649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-13T20:51:04.600104Z","caller":"traceutil/trace.go:171","msg":"trace[91376096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24952; }","duration":"263.166232ms","start":"2026-07-13T20:51:04.336905Z","end":"2026-07-13T20:51:04.600071Z","steps":["trace[91376096] 'agreement among raft nodes before linearized reading' (duration: 262.914596ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.600132Z","caller":"traceutil/trace.go:171","msg":"trace[1219796563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24952; }","duration":"329.111543ms","start":"2026-07-13T20:51:04.270999Z","end":"2026-07-13T20:51:04.600111Z","steps":["trace[1219796563] 'agreement among raft nodes before linearized reading' (duration: 328.885177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.600179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.270976Z","time spent":"329.194794ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-13T20:51:04.831071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.66682ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-13T20:51:04.831296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.527358Z","time spent":"303.930332ms","remote":"127.0.0.1:35224","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-13T20:51:04.831389Z","caller":"traceutil/trace.go:171","msg":"trace[1672728419] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"301.296868ms","start":"2026-07-13T20:51:04.530072Z","end":"2026-07-13T20:51:04.831369Z","steps":["trace[1672728419] 'process raft request' (duration: 301.123744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.831517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.53005Z","time spent":"301.40239ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:04.831638Z","caller":"traceutil/trace.go:171","msg":"trace[1066983709] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"226.371218ms","start":"2026-07-13T20:51:04.605249Z","end":"2026-07-13T20:51:04.83162Z","steps":["trace[1066983709] 'process raft request' (duration: 226.322697ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.831687Z","caller":"traceutil/trace.go:171","msg":"trace[509251370] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"225.794834ms","start":"2026-07-13T20:51:04.605821Z","end":"2026-07-13T20:51:04.831616Z","steps":["trace[509251370] 'process raft request' (duration: 225.770523ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.831676Z","caller":"traceutil/trace.go:171","msg":"trace[1106958290] linearizableReadLoop","detail":"{readStateIndex:25769; appliedIndex:25765; }","duration":"231.938353ms","start":"2026-07-13T20:51:04.599702Z","end":"2026-07-13T20:51:04.83164Z","steps":["trace[1106958290] 'read index received' (duration: 82.702438ms)","trace[1106958290] 'applied index is now lower than readState.Index' (duration: 149.231574ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:04.831645Z","caller":"traceutil/trace.go:171","msg":"trace[2026248756] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"248.145047ms","start":"2026-07-13T20:51:04.58347Z","end":"2026-07-13T20:51:04.831615Z","steps":["trace[2026248756] 'process raft request' (duration: 248.030794ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:04.831699Z","caller":"traceutil/trace.go:171","msg":"trace[151847645] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"300.815346ms","start":"2026-07-13T20:51:04.530854Z","end":"2026-07-13T20:51:04.83167Z","steps":["trace[151847645] 'process raft request' (duration: 300.468928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.831812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.313578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"warn","ts":"2026-07-13T20:51:04.831869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.530847Z","time spent":"300.919409ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:04.83189Z","caller":"traceutil/trace.go:171","msg":"trace[545874715] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:24957; }","duration":"301.426621ms","start":"2026-07-13T20:51:04.530442Z","end":"2026-07-13T20:51:04.831869Z","steps":["trace[545874715] 'agreement among raft nodes before linearized reading' (duration: 301.218796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.831889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.793433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:04.831924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.530401Z","time spent":"301.514693ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-07-13T20:51:04.831933Z","caller":"traceutil/trace.go:171","msg":"trace[2015072928] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24957; }","duration":"150.853344ms","start":"2026-07-13T20:51:04.681065Z","end":"2026-07-13T20:51:04.831919Z","steps":["trace[2015072928] 'agreement among raft nodes before linearized reading' (duration: 150.728711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.831916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.906042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-66f200c7-790a-42ca-89af-2321e06af256\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-07-13T20:51:04.832006Z","caller":"traceutil/trace.go:171","msg":"trace[199169681] range","detail":"{range_begin:/registry/persistentvolumes/pvc-66f200c7-790a-42ca-89af-2321e06af256; range_end:; response_count:1; response_revision:24957; }","duration":"302.115798ms","start":"2026-07-13T20:51:04.529869Z","end":"2026-07-13T20:51:04.831985Z","steps":["trace[199169681] 'agreement among raft nodes before linearized reading' (duration: 301.819591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.832049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.529849Z","time spent":"302.190199ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-66f200c7-790a-42ca-89af-2321e06af256\" "} {"level":"warn","ts":"2026-07-13T20:51:04.831916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.20361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-07-13T20:51:04.832102Z","caller":"traceutil/trace.go:171","msg":"trace[107610258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24957; }","duration":"302.419334ms","start":"2026-07-13T20:51:04.529669Z","end":"2026-07-13T20:51:04.832088Z","steps":["trace[107610258] 'agreement among raft nodes before linearized reading' (duration: 302.092076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:04.832139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:04.529649Z","time spent":"302.478896ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-07-13T20:51:05.194359Z","caller":"traceutil/trace.go:171","msg":"trace[1024239846] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"137.190372ms","start":"2026-07-13T20:51:05.057135Z","end":"2026-07-13T20:51:05.194326Z","steps":["trace[1024239846] 'process raft request' (duration: 136.053274ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:05.487403Z","caller":"traceutil/trace.go:171","msg":"trace[1586341132] linearizableReadLoop","detail":"{readStateIndex:25776; appliedIndex:25773; }","duration":"423.856044ms","start":"2026-07-13T20:51:05.063516Z","end":"2026-07-13T20:51:05.487372Z","steps":["trace[1586341132] 'read index received' (duration: 129.641469ms)","trace[1586341132] 'applied index is now lower than readState.Index' (duration: 294.213095ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:05.487955Z","caller":"traceutil/trace.go:171","msg":"trace[2115491468] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"429.87659ms","start":"2026-07-13T20:51:05.057491Z","end":"2026-07-13T20:51:05.487367Z","steps":["trace[2115491468] 'process raft request' (duration: 419.990299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:05.487997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.452249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T20:51:05.487663Z","caller":"traceutil/trace.go:171","msg":"trace[1148952130] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"426.812815ms","start":"2026-07-13T20:51:05.060828Z","end":"2026-07-13T20:51:05.487641Z","steps":["trace[1148952130] 'process raft request' (duration: 426.469567ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:05.488104Z","caller":"traceutil/trace.go:171","msg":"trace[1567731617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24962; }","duration":"424.599402ms","start":"2026-07-13T20:51:05.06348Z","end":"2026-07-13T20:51:05.488079Z","steps":["trace[1567731617] 'agreement among raft nodes before linearized reading' (duration: 424.246873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:05.488127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:05.057474Z","time spent":"430.554706ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:05.488165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:05.060818Z","time spent":"427.271926ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:05.48817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:05.063451Z","time spent":"424.705415ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T20:51:05.488243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.856488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:05.488311Z","caller":"traceutil/trace.go:171","msg":"trace[825120024] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24962; }","duration":"383.405661ms","start":"2026-07-13T20:51:05.10489Z","end":"2026-07-13T20:51:05.488296Z","steps":["trace[825120024] 'agreement among raft nodes before linearized reading' (duration: 383.229477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:05.488374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:05.104874Z","time spent":"383.489423ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-13T20:51:05.48838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.136266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:05.48848Z","caller":"traceutil/trace.go:171","msg":"trace[198629596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24962; }","duration":"150.257339ms","start":"2026-07-13T20:51:05.338195Z","end":"2026-07-13T20:51:05.488452Z","steps":["trace[198629596] 'agreement among raft nodes before linearized reading' (duration: 150.141206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:05.759868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.554989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:05.760264Z","caller":"traceutil/trace.go:171","msg":"trace[134456858] linearizableReadLoop","detail":"{readStateIndex:25777; appliedIndex:25776; }","duration":"268.039729ms","start":"2026-07-13T20:51:05.491913Z","end":"2026-07-13T20:51:05.759953Z","steps":["trace[134456858] 'read index received' (duration: 114.020749ms)","trace[134456858] 'applied index is now lower than readState.Index' (duration: 154.01491ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:05.760242Z","caller":"traceutil/trace.go:171","msg":"trace[2079750136] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"268.90141ms","start":"2026-07-13T20:51:05.491306Z","end":"2026-07-13T20:51:05.760207Z","steps":["trace[2079750136] 'process raft request' (duration: 114.711946ms)","trace[2079750136] 'compare' (duration: 153.315693ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:05.760391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.45191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:05.760479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.137872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-13T20:51:05.760457Z","caller":"traceutil/trace.go:171","msg":"trace[344514108] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24963; }","duration":"268.565262ms","start":"2026-07-13T20:51:05.491875Z","end":"2026-07-13T20:51:05.760441Z","steps":["trace[344514108] 'agreement among raft nodes before linearized reading' (duration: 268.431749ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:05.76051Z","caller":"traceutil/trace.go:171","msg":"trace[207641258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24963; }","duration":"163.202243ms","start":"2026-07-13T20:51:05.597298Z","end":"2026-07-13T20:51:05.7605Z","steps":["trace[207641258] 'agreement among raft nodes before linearized reading' (duration: 163.07741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:06.061534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.722194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:06.061633Z","caller":"traceutil/trace.go:171","msg":"trace[628201318] linearizableReadLoop","detail":"{readStateIndex:25778; appliedIndex:25777; }","duration":"296.083771ms","start":"2026-07-13T20:51:05.765534Z","end":"2026-07-13T20:51:06.061617Z","steps":["trace[628201318] 'read index received' (duration: 142.174723ms)","trace[628201318] 'applied index is now lower than readState.Index' (duration: 153.907718ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:06.061688Z","caller":"traceutil/trace.go:171","msg":"trace[1352471283] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"296.491441ms","start":"2026-07-13T20:51:05.765172Z","end":"2026-07-13T20:51:06.061663Z","steps":["trace[1352471283] 'process raft request' (duration: 142.565613ms)","trace[1352471283] 'compare' (duration: 153.624731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:06.061772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.235084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18431"} {"level":"info","ts":"2026-07-13T20:51:06.061809Z","caller":"traceutil/trace.go:171","msg":"trace[1896375508] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24964; }","duration":"296.286066ms","start":"2026-07-13T20:51:05.765511Z","end":"2026-07-13T20:51:06.061797Z","steps":["trace[1896375508] 'agreement among raft nodes before linearized reading' (duration: 296.153293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:06.546351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.749868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:06.546455Z","caller":"traceutil/trace.go:171","msg":"trace[1455911694] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25778; }","duration":"207.12229ms","start":"2026-07-13T20:51:06.339317Z","end":"2026-07-13T20:51:06.54644Z","steps":["trace[1455911694] 'read index received' (duration: 10.221058ms)","trace[1455911694] 'applied index is now lower than readState.Index' (duration: 196.899722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:06.546548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.238863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:06.546581Z","caller":"traceutil/trace.go:171","msg":"trace[633479727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24965; }","duration":"207.307125ms","start":"2026-07-13T20:51:06.339266Z","end":"2026-07-13T20:51:06.546573Z","steps":["trace[633479727] 'agreement among raft nodes before linearized reading' (duration: 207.218553ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:06.546774Z","caller":"traceutil/trace.go:171","msg":"trace[1543400204] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"315.312318ms","start":"2026-07-13T20:51:06.23145Z","end":"2026-07-13T20:51:06.546762Z","steps":["trace[1543400204] 'process raft request' (duration: 118.078149ms)","trace[1543400204] 'compare' (duration: 196.490792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:06.54684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:06.231422Z","time spent":"315.385829ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:12.888986Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000064eb.wal"} {"level":"warn","ts":"2026-07-13T20:51:16.307662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.206157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-13T20:51:16.307727Z","caller":"traceutil/trace.go:171","msg":"trace[373902607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25048; }","duration":"117.280818ms","start":"2026-07-13T20:51:16.190432Z","end":"2026-07-13T20:51:16.307713Z","steps":["trace[373902607] 'range keys from in-memory index tree' (duration: 117.010352ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:16.719118Z","caller":"traceutil/trace.go:171","msg":"trace[1220581216] linearizableReadLoop","detail":"{readStateIndex:25865; appliedIndex:25864; }","duration":"104.357993ms","start":"2026-07-13T20:51:16.614727Z","end":"2026-07-13T20:51:16.719085Z","steps":["trace[1220581216] 'read index received' (duration: 104.159669ms)","trace[1220581216] 'applied index is now lower than readState.Index' (duration: 197.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:16.719177Z","caller":"traceutil/trace.go:171","msg":"trace[221222852] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"139.507697ms","start":"2026-07-13T20:51:16.579647Z","end":"2026-07-13T20:51:16.719155Z","steps":["trace[221222852] 'process raft request' (duration: 139.236261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:16.719356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.593319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:16.719519Z","caller":"traceutil/trace.go:171","msg":"trace[1726055777] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25049; }","duration":"104.818394ms","start":"2026-07-13T20:51:16.614673Z","end":"2026-07-13T20:51:16.719491Z","steps":["trace[1726055777] 'agreement among raft nodes before linearized reading' (duration: 104.535137ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.043749Z","caller":"traceutil/trace.go:171","msg":"trace[1740247832] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"173.195417ms","start":"2026-07-13T20:51:16.870535Z","end":"2026-07-13T20:51:17.043731Z","steps":["trace[1740247832] 'process raft request' (duration: 173.054394ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.16683Z","caller":"traceutil/trace.go:171","msg":"trace[680222013] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"293.950859ms","start":"2026-07-13T20:51:16.872842Z","end":"2026-07-13T20:51:17.166793Z","steps":["trace[680222013] 'process raft request' (duration: 290.593368ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.166973Z","caller":"traceutil/trace.go:171","msg":"trace[836974991] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25866; }","duration":"261.696575ms","start":"2026-07-13T20:51:16.905254Z","end":"2026-07-13T20:51:17.16695Z","steps":["trace[836974991] 'read index received' (duration: 138.406931ms)","trace[836974991] 'applied index is now lower than readState.Index' (duration: 123.288474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:17.167202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.936652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-13T20:51:17.167247Z","caller":"traceutil/trace.go:171","msg":"trace[1073485359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25053; }","duration":"262.019343ms","start":"2026-07-13T20:51:16.905212Z","end":"2026-07-13T20:51:17.167232Z","steps":["trace[1073485359] 'agreement among raft nodes before linearized reading' (duration: 261.87851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.167276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.946056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-13T20:51:17.167356Z","caller":"traceutil/trace.go:171","msg":"trace[1220533207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25053; }","duration":"256.084569ms","start":"2026-07-13T20:51:16.911252Z","end":"2026-07-13T20:51:17.167337Z","steps":["trace[1220533207] 'agreement among raft nodes before linearized reading' (duration: 255.891505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.167574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.702656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-13T20:51:17.167613Z","caller":"traceutil/trace.go:171","msg":"trace[93413866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25053; }","duration":"240.779988ms","start":"2026-07-13T20:51:16.926823Z","end":"2026-07-13T20:51:17.167603Z","steps":["trace[93413866] 'agreement among raft nodes before linearized reading' (duration: 240.638394ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.407384Z","caller":"traceutil/trace.go:171","msg":"trace[1148444888] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"225.718641ms","start":"2026-07-13T20:51:17.181639Z","end":"2026-07-13T20:51:17.407358Z","steps":["trace[1148444888] 'process raft request' (duration: 222.235786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.735515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.722462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:51:17.735772Z","caller":"traceutil/trace.go:171","msg":"trace[127242342] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25869; }","duration":"546.570141ms","start":"2026-07-13T20:51:17.189184Z","end":"2026-07-13T20:51:17.735754Z","steps":["trace[127242342] 'read index received' (duration: 214.692413ms)","trace[127242342] 'applied index is now lower than readState.Index' (duration: 331.876888ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:17.735759Z","caller":"traceutil/trace.go:171","msg":"trace[595411018] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"553.945701ms","start":"2026-07-13T20:51:17.181785Z","end":"2026-07-13T20:51:17.73573Z","steps":["trace[595411018] 'process raft request' (duration: 553.831788ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.735787Z","caller":"traceutil/trace.go:171","msg":"trace[1613506918] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"507.916913ms","start":"2026-07-13T20:51:17.227852Z","end":"2026-07-13T20:51:17.735769Z","steps":["trace[1613506918] 'process raft request' (duration: 507.827441ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.735787Z","caller":"traceutil/trace.go:171","msg":"trace[73585206] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"554.091424ms","start":"2026-07-13T20:51:17.181659Z","end":"2026-07-13T20:51:17.73575Z","steps":["trace[73585206] 'process raft request' (duration: 398.034224ms)","trace[73585206] 'compare' (duration: 155.358874ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:17.735922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.227806Z","time spent":"508.049926ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:17.73593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.18176Z","time spent":"554.089464ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:17.73597Z","caller":"traceutil/trace.go:171","msg":"trace[983967187] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"211.474094ms","start":"2026-07-13T20:51:17.524485Z","end":"2026-07-13T20:51:17.735959Z","steps":["trace[983967187] 'process raft request' (duration: 211.238809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.735969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.181646Z","time spent":"554.254848ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:17.736079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.878029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:17.736143Z","caller":"traceutil/trace.go:171","msg":"trace[486680663] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25058; }","duration":"546.975181ms","start":"2026-07-13T20:51:17.189149Z","end":"2026-07-13T20:51:17.736125Z","steps":["trace[486680663] 'agreement among raft nodes before linearized reading' (duration: 546.717875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.736201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.603987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:17.736189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.189137Z","time spent":"547.037592ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:51:17.736247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.700879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:17.736233Z","caller":"traceutil/trace.go:171","msg":"trace[1928904734] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"193.736964ms","start":"2026-07-13T20:51:17.542476Z","end":"2026-07-13T20:51:17.736213Z","steps":["trace[1928904734] 'process raft request' (duration: 193.429147ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.736263Z","caller":"traceutil/trace.go:171","msg":"trace[1972522817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25059; }","duration":"400.73949ms","start":"2026-07-13T20:51:17.335519Z","end":"2026-07-13T20:51:17.736259Z","steps":["trace[1972522817] 'agreement among raft nodes before linearized reading' (duration: 400.7158ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:17.736223Z","caller":"traceutil/trace.go:171","msg":"trace[1194013189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25059; }","duration":"407.668158ms","start":"2026-07-13T20:51:17.328549Z","end":"2026-07-13T20:51:17.736217Z","steps":["trace[1194013189] 'agreement among raft nodes before linearized reading' (duration: 407.606747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:17.736279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.335507Z","time spent":"400.768141ms","remote":"127.0.0.1:35206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:51:17.736302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:17.328527Z","time spent":"407.76346ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:51:18.671615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.526089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:18.671711Z","caller":"traceutil/trace.go:171","msg":"trace[80498312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25061; }","duration":"333.681243ms","start":"2026-07-13T20:51:18.33801Z","end":"2026-07-13T20:51:18.671691Z","steps":["trace[80498312] 'range keys from in-memory index tree' (duration: 333.428067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:18.671758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:18.337989Z","time spent":"333.757666ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:51:18.672137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.756835ms","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:6"} {"level":"info","ts":"2026-07-13T20:51:18.672176Z","caller":"traceutil/trace.go:171","msg":"trace[110364857] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25061; }","duration":"396.839937ms","start":"2026-07-13T20:51:18.275321Z","end":"2026-07-13T20:51:18.672161Z","steps":["trace[110364857] 'count revisions from in-memory index tree' (duration: 396.661643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:18.672216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:18.275299Z","time spent":"396.898958ms","remote":"127.0.0.1:43104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:51:18.672202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.358819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:51:18.672306Z","caller":"traceutil/trace.go:171","msg":"trace[2087598269] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25061; }","duration":"224.541503ms","start":"2026-07-13T20:51:18.447737Z","end":"2026-07-13T20:51:18.672279Z","steps":["trace[2087598269] 'count revisions from in-memory index tree' (duration: 224.182595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:24.023994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.425415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-13T20:51:24.024058Z","caller":"traceutil/trace.go:171","msg":"trace[761575480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25118; }","duration":"113.535158ms","start":"2026-07-13T20:51:23.91051Z","end":"2026-07-13T20:51:24.024045Z","steps":["trace[761575480] 'range keys from in-memory index tree' (duration: 113.294982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:24.41866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.55272ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-13T20:51:24.41885Z","caller":"traceutil/trace.go:171","msg":"trace[1439219180] linearizableReadLoop","detail":"{readStateIndex:25937; appliedIndex:25936; }","duration":"220.803962ms","start":"2026-07-13T20:51:24.197987Z","end":"2026-07-13T20:51:24.418791Z","steps":["trace[1439219180] 'read index received' (duration: 67.772µs)","trace[1439219180] 'applied index is now lower than readState.Index' (duration: 220.73415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:24.418942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:24.037439Z","time spent":"381.494995ms","remote":"127.0.0.1:35224","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-13T20:51:24.419348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.354735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-13T20:51:24.419404Z","caller":"traceutil/trace.go:171","msg":"trace[1369881961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25119; }","duration":"221.469048ms","start":"2026-07-13T20:51:24.197917Z","end":"2026-07-13T20:51:24.419386Z","steps":["trace[1369881961] 'agreement among raft nodes before linearized reading' (duration: 221.283594ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:24.467213Z","caller":"traceutil/trace.go:171","msg":"trace[13262366] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"208.675008ms","start":"2026-07-13T20:51:24.258512Z","end":"2026-07-13T20:51:24.467187Z","steps":["trace[13262366] 'process raft request' (duration: 208.348121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:24.467528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.909881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:24.46771Z","caller":"traceutil/trace.go:171","msg":"trace[1226990332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25120; }","duration":"129.226448ms","start":"2026-07-13T20:51:24.338463Z","end":"2026-07-13T20:51:24.467689Z","steps":["trace[1226990332] 'agreement among raft nodes before linearized reading' (duration: 128.969552ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:24.623273Z","caller":"traceutil/trace.go:171","msg":"trace[946661731] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25938; }","duration":"156.098741ms","start":"2026-07-13T20:51:24.467152Z","end":"2026-07-13T20:51:24.62325Z","steps":["trace[946661731] 'read index received' (duration: 116.902969ms)","trace[946661731] 'applied index is now lower than readState.Index' (duration: 39.194462ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:24.623402Z","caller":"traceutil/trace.go:171","msg":"trace[386773290] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"199.615618ms","start":"2026-07-13T20:51:24.423775Z","end":"2026-07-13T20:51:24.623391Z","steps":["trace[386773290] 'process raft request' (duration: 199.419944ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:24.623453Z","caller":"traceutil/trace.go:171","msg":"trace[343480865] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"203.126973ms","start":"2026-07-13T20:51:24.420309Z","end":"2026-07-13T20:51:24.623436Z","steps":["trace[343480865] 'process raft request' (duration: 163.827539ms)","trace[343480865] 'compare' (duration: 38.935626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:24.623707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.238824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-13T20:51:24.62373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.001475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:24.623756Z","caller":"traceutil/trace.go:171","msg":"trace[1889868873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25122; }","duration":"155.085127ms","start":"2026-07-13T20:51:24.468662Z","end":"2026-07-13T20:51:24.623748Z","steps":["trace[1889868873] 'agreement among raft nodes before linearized reading' (duration: 155.038056ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:24.623758Z","caller":"traceutil/trace.go:171","msg":"trace[1027864810] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25122; }","duration":"177.342017ms","start":"2026-07-13T20:51:24.446401Z","end":"2026-07-13T20:51:24.623743Z","steps":["trace[1027864810] 'agreement among raft nodes before linearized reading' (duration: 177.251445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:24.863906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.400275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:24.864085Z","caller":"traceutil/trace.go:171","msg":"trace[432288900] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25122; }","duration":"125.625461ms","start":"2026-07-13T20:51:24.738437Z","end":"2026-07-13T20:51:24.864062Z","steps":["trace[432288900] 'range keys from in-memory index tree' (duration: 125.338644ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:24.864352Z","caller":"traceutil/trace.go:171","msg":"trace[102888103] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"115.029963ms","start":"2026-07-13T20:51:24.749305Z","end":"2026-07-13T20:51:24.864335Z","steps":["trace[102888103] 'process raft request' (duration: 100.999003ms)","trace[102888103] 'compare' (duration: 13.58548ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:24.86449Z","caller":"traceutil/trace.go:171","msg":"trace[1032454481] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"109.324515ms","start":"2026-07-13T20:51:24.755157Z","end":"2026-07-13T20:51:24.864482Z","steps":["trace[1032454481] 'process raft request' (duration: 108.851984ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.203526Z","caller":"traceutil/trace.go:171","msg":"trace[451159916] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"326.595492ms","start":"2026-07-13T20:51:24.876904Z","end":"2026-07-13T20:51:25.203499Z","steps":["trace[451159916] 'process raft request' (duration: 305.191582ms)","trace[451159916] 'compare' (duration: 20.238741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:25.203688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:24.876891Z","time spent":"326.730275ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:51:25.203726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.216427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:25.203792Z","caller":"traceutil/trace.go:171","msg":"trace[350150880] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25126; }","duration":"208.323239ms","start":"2026-07-13T20:51:24.995454Z","end":"2026-07-13T20:51:25.203777Z","steps":["trace[350150880] 'agreement among raft nodes before linearized reading' (duration: 208.173555ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.205581Z","caller":"traceutil/trace.go:171","msg":"trace[427990683] linearizableReadLoop","detail":"{readStateIndex:25944; appliedIndex:25943; }","duration":"207.95521ms","start":"2026-07-13T20:51:24.995489Z","end":"2026-07-13T20:51:25.203444Z","steps":["trace[427990683] 'read index received' (duration: 186.618651ms)","trace[427990683] 'applied index is now lower than readState.Index' (duration: 21.335249ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:25.229498Z","caller":"traceutil/trace.go:171","msg":"trace[1117806522] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"159.587736ms","start":"2026-07-13T20:51:25.069893Z","end":"2026-07-13T20:51:25.229481Z","steps":["trace[1117806522] 'process raft request' (duration: 159.462853ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.408544Z","caller":"traceutil/trace.go:171","msg":"trace[166061316] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25944; }","duration":"200.071048ms","start":"2026-07-13T20:51:25.208456Z","end":"2026-07-13T20:51:25.408527Z","steps":["trace[166061316] 'read index received' (duration: 20.889887ms)","trace[166061316] 'applied index is now lower than readState.Index' (duration: 179.180511ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:25.408852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.865609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-07-13T20:51:25.408879Z","caller":"traceutil/trace.go:171","msg":"trace[311930454] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25129; }","duration":"198.929021ms","start":"2026-07-13T20:51:25.209942Z","end":"2026-07-13T20:51:25.408871Z","steps":["trace[311930454] 'agreement among raft nodes before linearized reading' (duration: 198.820149ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.408832Z","caller":"traceutil/trace.go:171","msg":"trace[138337231] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"209.836646ms","start":"2026-07-13T20:51:25.198948Z","end":"2026-07-13T20:51:25.408784Z","steps":["trace[138337231] 'process raft request' (duration: 209.440097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:25.408875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.400506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-07-13T20:51:25.408939Z","caller":"traceutil/trace.go:171","msg":"trace[1751911614] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25129; }","duration":"200.473308ms","start":"2026-07-13T20:51:25.208449Z","end":"2026-07-13T20:51:25.408923Z","steps":["trace[1751911614] 'agreement among raft nodes before linearized reading' (duration: 200.284753ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.409054Z","caller":"traceutil/trace.go:171","msg":"trace[2141143576] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"195.126338ms","start":"2026-07-13T20:51:25.213922Z","end":"2026-07-13T20:51:25.409048Z","steps":["trace[2141143576] 'process raft request' (duration: 194.577545ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.715331Z","caller":"traceutil/trace.go:171","msg":"trace[1732750832] linearizableReadLoop","detail":"{readStateIndex:25948; appliedIndex:25947; }","duration":"277.126099ms","start":"2026-07-13T20:51:25.438179Z","end":"2026-07-13T20:51:25.715305Z","steps":["trace[1732750832] 'read index received' (duration: 182.635195ms)","trace[1732750832] 'applied index is now lower than readState.Index' (duration: 94.489754ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:25.715404Z","caller":"traceutil/trace.go:171","msg":"trace[20447830] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"298.435838ms","start":"2026-07-13T20:51:25.416934Z","end":"2026-07-13T20:51:25.71537Z","steps":["trace[20447830] 'process raft request' (duration: 204.118597ms)","trace[20447830] 'compare' (duration: 94.137936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:25.71557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.373957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-13T20:51:25.715612Z","caller":"traceutil/trace.go:171","msg":"trace[942166787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25130; }","duration":"277.455558ms","start":"2026-07-13T20:51:25.438145Z","end":"2026-07-13T20:51:25.715601Z","steps":["trace[942166787] 'agreement among raft nodes before linearized reading' (duration: 277.281564ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:25.86613Z","caller":"traceutil/trace.go:171","msg":"trace[677828997] linearizableReadLoop","detail":"{readStateIndex:25949; appliedIndex:25948; }","duration":"144.653402ms","start":"2026-07-13T20:51:25.72146Z","end":"2026-07-13T20:51:25.866113Z","steps":["trace[677828997] 'read index received' (duration: 138.36248ms)","trace[677828997] 'applied index is now lower than readState.Index' (duration: 6.289102ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:25.866252Z","caller":"traceutil/trace.go:171","msg":"trace[793107751] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"144.743235ms","start":"2026-07-13T20:51:25.721316Z","end":"2026-07-13T20:51:25.866059Z","steps":["trace[793107751] 'process raft request' (duration: 138.373061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:25.866425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.950359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-32602279-abdc-4ed4-930e-96e21159382f\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-07-13T20:51:25.866514Z","caller":"traceutil/trace.go:171","msg":"trace[1824492835] range","detail":"{range_begin:/registry/persistentvolumes/pvc-32602279-abdc-4ed4-930e-96e21159382f; range_end:; response_count:1; response_revision:25131; }","duration":"145.062512ms","start":"2026-07-13T20:51:25.721433Z","end":"2026-07-13T20:51:25.866496Z","steps":["trace[1824492835] 'agreement among raft nodes before linearized reading' (duration: 144.765095ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:26.077769Z","caller":"traceutil/trace.go:171","msg":"trace[358472870] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"351.901966ms","start":"2026-07-13T20:51:25.725833Z","end":"2026-07-13T20:51:26.077735Z","steps":["trace[358472870] 'process raft request' (duration: 297.643958ms)","trace[358472870] 'compare' (duration: 53.652403ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:26.077879Z","caller":"traceutil/trace.go:171","msg":"trace[1940301405] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"307.864967ms","start":"2026-07-13T20:51:25.76999Z","end":"2026-07-13T20:51:26.077855Z","steps":["trace[1940301405] 'process raft request' (duration: 307.730754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.077932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.725814Z","time spent":"352.03365ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2793,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:26.077994Z","caller":"traceutil/trace.go:171","msg":"trace[1581149659] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25949; }","duration":"211.765824ms","start":"2026-07-13T20:51:25.866208Z","end":"2026-07-13T20:51:26.077974Z","steps":["trace[1581149659] 'read index received' (duration: 157.192308ms)","trace[1581149659] 'applied index is now lower than readState.Index' (duration: 54.572546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:51:26.078024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.76998Z","time spent":"307.963459ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:51:26.078151Z","caller":"traceutil/trace.go:171","msg":"trace[718490810] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"319.754745ms","start":"2026-07-13T20:51:25.758386Z","end":"2026-07-13T20:51:26.078141Z","steps":["trace[718490810] 'process raft request' (duration: 319.208952ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:26.078192Z","caller":"traceutil/trace.go:171","msg":"trace[984054031] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"304.949706ms","start":"2026-07-13T20:51:25.773234Z","end":"2026-07-13T20:51:26.078183Z","steps":["trace[984054031] 'process raft request' (duration: 304.541866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.078202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.758359Z","time spent":"319.814076ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:26.078275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.773214Z","time spent":"304.999327ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:51:26.078438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-13T20:51:26.078472Z","caller":"traceutil/trace.go:171","msg":"trace[1312620191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25135; }","duration":"301.111282ms","start":"2026-07-13T20:51:25.777352Z","end":"2026-07-13T20:51:26.078463Z","steps":["trace[1312620191] 'agreement among raft nodes before linearized reading' (duration: 300.99835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.078485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.079001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-13T20:51:26.078493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.777336Z","time spent":"301.152253ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T20:51:26.078489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.868844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-13T20:51:26.078508Z","caller":"traceutil/trace.go:171","msg":"trace[262186398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25135; }","duration":"301.109582ms","start":"2026-07-13T20:51:25.777391Z","end":"2026-07-13T20:51:26.0785Z","steps":["trace[262186398] 'agreement among raft nodes before linearized reading' (duration: 300.9972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.07853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.777387Z","time spent":"301.139213ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-13T20:51:26.078534Z","caller":"traceutil/trace.go:171","msg":"trace[1928549188] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25135; }","duration":"206.953566ms","start":"2026-07-13T20:51:25.871567Z","end":"2026-07-13T20:51:26.078521Z","steps":["trace[1928549188] 'agreement among raft nodes before linearized reading' (duration: 206.796132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.078667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.381829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:51:26.078694Z","caller":"traceutil/trace.go:171","msg":"trace[404065662] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25135; }","duration":"308.43112ms","start":"2026-07-13T20:51:25.770255Z","end":"2026-07-13T20:51:26.078686Z","steps":["trace[404065662] 'agreement among raft nodes before linearized reading' (duration: 308.373728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:26.078712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:51:25.770248Z","time spent":"308.459321ms","remote":"127.0.0.1:35642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":21,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:51:26.07873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.622436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:26.078768Z","caller":"traceutil/trace.go:171","msg":"trace[1212792498] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25135; }","duration":"194.696597ms","start":"2026-07-13T20:51:25.884061Z","end":"2026-07-13T20:51:26.078757Z","steps":["trace[1212792498] 'agreement among raft nodes before linearized reading' (duration: 194.630086ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:26.993749Z","caller":"traceutil/trace.go:171","msg":"trace[623237512] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"124.967685ms","start":"2026-07-13T20:51:26.868752Z","end":"2026-07-13T20:51:26.99372Z","steps":["trace[623237512] 'process raft request' (duration: 124.790721ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:27.055085Z","caller":"traceutil/trace.go:171","msg":"trace[1835297903] linearizableReadLoop","detail":"{readStateIndex:26013; appliedIndex:26011; }","duration":"170.724546ms","start":"2026-07-13T20:51:26.884338Z","end":"2026-07-13T20:51:27.055062Z","steps":["trace[1835297903] 'read index received' (duration: 109.211432ms)","trace[1835297903] 'applied index is now lower than readState.Index' (duration: 61.512044ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:27.055208Z","caller":"traceutil/trace.go:171","msg":"trace[1334854879] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"186.395497ms","start":"2026-07-13T20:51:26.8688Z","end":"2026-07-13T20:51:27.055195Z","steps":["trace[1334854879] 'process raft request' (duration: 186.141411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:27.05542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.068534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-07-13T20:51:27.055481Z","caller":"traceutil/trace.go:171","msg":"trace[1993661283] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25195; }","duration":"171.132885ms","start":"2026-07-13T20:51:26.88433Z","end":"2026-07-13T20:51:27.055463Z","steps":["trace[1993661283] 'agreement among raft nodes before linearized reading' (duration: 170.978572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.226522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.880699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-13T20:51:34.226586Z","caller":"traceutil/trace.go:171","msg":"trace[226826845] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:25279; }","duration":"198.987712ms","start":"2026-07-13T20:51:34.027588Z","end":"2026-07-13T20:51:34.226575Z","steps":["trace[226826845] 'range keys from in-memory index tree' (duration: 198.779507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.226694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.549651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-07-13T20:51:34.226763Z","caller":"traceutil/trace.go:171","msg":"trace[1516533572] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25279; }","duration":"130.663694ms","start":"2026-07-13T20:51:34.096081Z","end":"2026-07-13T20:51:34.226745Z","steps":["trace[1516533572] 'range keys from in-memory index tree' (duration: 130.374346ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.351281Z","caller":"traceutil/trace.go:171","msg":"trace[1812134225] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"243.462503ms","start":"2026-07-13T20:51:34.107796Z","end":"2026-07-13T20:51:34.351258Z","steps":["trace[1812134225] 'process raft request' (duration: 243.295749ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.380555Z","caller":"traceutil/trace.go:171","msg":"trace[985558503] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"269.785423ms","start":"2026-07-13T20:51:34.110746Z","end":"2026-07-13T20:51:34.380532Z","steps":["trace[985558503] 'process raft request' (duration: 269.619619ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.380933Z","caller":"traceutil/trace.go:171","msg":"trace[2090591780] linearizableReadLoop","detail":"{readStateIndex:26102; appliedIndex:26099; }","duration":"185.777853ms","start":"2026-07-13T20:51:34.195113Z","end":"2026-07-13T20:51:34.380891Z","steps":["trace[2090591780] 'read index received' (duration: 156.007999ms)","trace[2090591780] 'applied index is now lower than readState.Index' (duration: 29.767714ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:34.380893Z","caller":"traceutil/trace.go:171","msg":"trace[1862864717] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"268.161533ms","start":"2026-07-13T20:51:34.112686Z","end":"2026-07-13T20:51:34.380847Z","steps":["trace[1862864717] 'process raft request' (duration: 267.798574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.381197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.060529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T20:51:34.381258Z","caller":"traceutil/trace.go:171","msg":"trace[81038560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25282; }","duration":"186.173922ms","start":"2026-07-13T20:51:34.195068Z","end":"2026-07-13T20:51:34.381242Z","steps":["trace[81038560] 'agreement among raft nodes before linearized reading' (duration: 185.956727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.38124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.846459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:51:34.381329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.747767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T20:51:34.381353Z","caller":"traceutil/trace.go:171","msg":"trace[1755763069] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25282; }","duration":"156.966622ms","start":"2026-07-13T20:51:34.224353Z","end":"2026-07-13T20:51:34.38132Z","steps":["trace[1755763069] 'agreement among raft nodes before linearized reading' (duration: 156.790528ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.381384Z","caller":"traceutil/trace.go:171","msg":"trace[1476257267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25282; }","duration":"184.86661ms","start":"2026-07-13T20:51:34.196501Z","end":"2026-07-13T20:51:34.381368Z","steps":["trace[1476257267] 'agreement among raft nodes before linearized reading' (duration: 184.601474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.381446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.255322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T20:51:34.382602Z","caller":"traceutil/trace.go:171","msg":"trace[1470013403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25282; }","duration":"182.244976ms","start":"2026-07-13T20:51:34.200159Z","end":"2026-07-13T20:51:34.382404Z","steps":["trace[1470013403] 'agreement among raft nodes before linearized reading' (duration: 181.16005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:34.3815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.192229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:34.382773Z","caller":"traceutil/trace.go:171","msg":"trace[319362646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25282; }","duration":"163.46403ms","start":"2026-07-13T20:51:34.219274Z","end":"2026-07-13T20:51:34.382738Z","steps":["trace[319362646] 'agreement among raft nodes before linearized reading' (duration: 162.053656ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.510094Z","caller":"traceutil/trace.go:171","msg":"trace[1793311642] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"128.170273ms","start":"2026-07-13T20:51:34.381858Z","end":"2026-07-13T20:51:34.510028Z","steps":["trace[1793311642] 'process raft request' (duration: 66.531936ms)","trace[1793311642] 'compare' (duration: 61.489413ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:34.53029Z","caller":"traceutil/trace.go:171","msg":"trace[1913387088] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"142.45202ms","start":"2026-07-13T20:51:34.387813Z","end":"2026-07-13T20:51:34.530265Z","steps":["trace[1913387088] 'process raft request' (duration: 142.400499ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.530499Z","caller":"traceutil/trace.go:171","msg":"trace[1256924813] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"142.768608ms","start":"2026-07-13T20:51:34.387701Z","end":"2026-07-13T20:51:34.53047Z","steps":["trace[1256924813] 'process raft request' (duration: 142.307067ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:34.530586Z","caller":"traceutil/trace.go:171","msg":"trace[1250902769] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"142.842099ms","start":"2026-07-13T20:51:34.387732Z","end":"2026-07-13T20:51:34.530574Z","steps":["trace[1250902769] 'process raft request' (duration: 142.44849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:45.594612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.894518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:51:45.594739Z","caller":"traceutil/trace.go:171","msg":"trace[1714177103] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25382; }","duration":"126.083472ms","start":"2026-07-13T20:51:45.468632Z","end":"2026-07-13T20:51:45.594716Z","steps":["trace[1714177103] 'count revisions from in-memory index tree' (duration: 125.806305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:45.594826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.490557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-13T20:51:45.594911Z","caller":"traceutil/trace.go:171","msg":"trace[546121991] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25382; }","duration":"146.631081ms","start":"2026-07-13T20:51:45.448258Z","end":"2026-07-13T20:51:45.594889Z","steps":["trace[546121991] 'range keys from in-memory index tree' (duration: 146.256032ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:45.606598Z","caller":"traceutil/trace.go:171","msg":"trace[975358864] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"134.428404ms","start":"2026-07-13T20:51:45.472157Z","end":"2026-07-13T20:51:45.606585Z","steps":["trace[975358864] 'process raft request' (duration: 134.326142ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:45.630097Z","caller":"traceutil/trace.go:171","msg":"trace[881332498] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"153.937599ms","start":"2026-07-13T20:51:45.476115Z","end":"2026-07-13T20:51:45.630053Z","steps":["trace[881332498] 'process raft request' (duration: 153.464847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:45.912214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.061548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c4376a18-3b54-4c46-ba08-dfdf3dbb5c2b\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-07-13T20:51:45.912296Z","caller":"traceutil/trace.go:171","msg":"trace[1439999779] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c4376a18-3b54-4c46-ba08-dfdf3dbb5c2b; range_end:; response_count:1; response_revision:25384; }","duration":"214.182471ms","start":"2026-07-13T20:51:45.698093Z","end":"2026-07-13T20:51:45.912275Z","steps":["trace[1439999779] 'range keys from in-memory index tree' (duration: 213.844963ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.355475Z","caller":"traceutil/trace.go:171","msg":"trace[765989482] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"127.991778ms","start":"2026-07-13T20:51:46.227458Z","end":"2026-07-13T20:51:46.355449Z","steps":["trace[765989482] 'process raft request' (duration: 127.816734ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.371792Z","caller":"traceutil/trace.go:171","msg":"trace[183149610] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26208; }","duration":"110.211326ms","start":"2026-07-13T20:51:46.261525Z","end":"2026-07-13T20:51:46.371736Z","steps":["trace[183149610] 'read index received' (duration: 93.678064ms)","trace[183149610] 'applied index is now lower than readState.Index' (duration: 16.532202ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:46.372102Z","caller":"traceutil/trace.go:171","msg":"trace[525112053] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"143.044364ms","start":"2026-07-13T20:51:46.229044Z","end":"2026-07-13T20:51:46.372088Z","steps":["trace[525112053] 'process raft request' (duration: 142.563333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:46.372304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.755519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:51:46.372339Z","caller":"traceutil/trace.go:171","msg":"trace[1951403992] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25387; }","duration":"110.836521ms","start":"2026-07-13T20:51:46.261491Z","end":"2026-07-13T20:51:46.372328Z","steps":["trace[1951403992] 'agreement among raft nodes before linearized reading' (duration: 110.758469ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.567372Z","caller":"traceutil/trace.go:171","msg":"trace[1280877676] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"192.881045ms","start":"2026-07-13T20:51:46.374436Z","end":"2026-07-13T20:51:46.567317Z","steps":["trace[1280877676] 'process raft request' (duration: 192.68254ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.599764Z","caller":"traceutil/trace.go:171","msg":"trace[1927852806] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"213.669829ms","start":"2026-07-13T20:51:46.386066Z","end":"2026-07-13T20:51:46.599735Z","steps":["trace[1927852806] 'process raft request' (duration: 213.462463ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.6Z","caller":"traceutil/trace.go:171","msg":"trace[857323319] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"175.357989ms","start":"2026-07-13T20:51:46.424629Z","end":"2026-07-13T20:51:46.599986Z","steps":["trace[857323319] 'process raft request' (duration: 175.056672ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.600021Z","caller":"traceutil/trace.go:171","msg":"trace[1831365497] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"174.644942ms","start":"2026-07-13T20:51:46.425355Z","end":"2026-07-13T20:51:46.6Z","steps":["trace[1831365497] 'process raft request' (duration: 174.532289ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.750975Z","caller":"traceutil/trace.go:171","msg":"trace[835478064] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"147.681107ms","start":"2026-07-13T20:51:46.603262Z","end":"2026-07-13T20:51:46.750943Z","steps":["trace[835478064] 'process raft request' (duration: 116.292293ms)","trace[835478064] 'compare' (duration: 31.28012ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:46.75206Z","caller":"traceutil/trace.go:171","msg":"trace[1093733896] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"147.181035ms","start":"2026-07-13T20:51:46.604856Z","end":"2026-07-13T20:51:46.752037Z","steps":["trace[1093733896] 'process raft request' (duration: 147.013791ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.752261Z","caller":"traceutil/trace.go:171","msg":"trace[18978286] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"147.169345ms","start":"2026-07-13T20:51:46.605076Z","end":"2026-07-13T20:51:46.752245Z","steps":["trace[18978286] 'process raft request' (duration: 146.918859ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:46.752589Z","caller":"traceutil/trace.go:171","msg":"trace[442108428] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"143.70263ms","start":"2026-07-13T20:51:46.608874Z","end":"2026-07-13T20:51:46.752577Z","steps":["trace[442108428] 'process raft request' (duration: 143.31091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:46.752909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.275866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-07-13T20:51:46.753043Z","caller":"traceutil/trace.go:171","msg":"trace[310393724] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25395; }","duration":"100.46049ms","start":"2026-07-13T20:51:46.652556Z","end":"2026-07-13T20:51:46.753016Z","steps":["trace[310393724] 'agreement among raft nodes before linearized reading' (duration: 100.141213ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:47.261625Z","caller":"traceutil/trace.go:171","msg":"trace[1067102767] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"103.73791ms","start":"2026-07-13T20:51:47.157858Z","end":"2026-07-13T20:51:47.261596Z","steps":["trace[1067102767] 'process raft request' (duration: 36.974308ms)","trace[1067102767] 'compare' (duration: 66.612108ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:47.470388Z","caller":"traceutil/trace.go:171","msg":"trace[178574255] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"171.671809ms","start":"2026-07-13T20:51:47.298694Z","end":"2026-07-13T20:51:47.470366Z","steps":["trace[178574255] 'process raft request' (duration: 171.535736ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:52.820838Z","caller":"traceutil/trace.go:171","msg":"trace[140866731] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"199.759051ms","start":"2026-07-13T20:51:52.621055Z","end":"2026-07-13T20:51:52.820814Z","steps":["trace[140866731] 'process raft request' (duration: 199.71536ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:52.820894Z","caller":"traceutil/trace.go:171","msg":"trace[84724195] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"201.294439ms","start":"2026-07-13T20:51:52.619567Z","end":"2026-07-13T20:51:52.820862Z","steps":["trace[84724195] 'process raft request' (duration: 112.628326ms)","trace[84724195] 'compare' (duration: 88.461828ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:54.973303Z","caller":"traceutil/trace.go:171","msg":"trace[755693259] linearizableReadLoop","detail":"{readStateIndex:26294; appliedIndex:26292; }","duration":"132.679533ms","start":"2026-07-13T20:51:54.8406Z","end":"2026-07-13T20:51:54.973279Z","steps":["trace[755693259] 'read index received' (duration: 33.75588ms)","trace[755693259] 'applied index is now lower than readState.Index' (duration: 98.922763ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:51:54.973472Z","caller":"traceutil/trace.go:171","msg":"trace[216512217] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"146.847647ms","start":"2026-07-13T20:51:54.826597Z","end":"2026-07-13T20:51:54.973444Z","steps":["trace[216512217] 'process raft request' (duration: 141.691802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:54.973587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.084285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-13T20:51:54.97366Z","caller":"traceutil/trace.go:171","msg":"trace[202009683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25469; }","duration":"122.200928ms","start":"2026-07-13T20:51:54.851442Z","end":"2026-07-13T20:51:54.973642Z","steps":["trace[202009683] 'agreement among raft nodes before linearized reading' (duration: 122.037624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:54.973587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.97393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-13T20:51:54.973727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.247216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-13T20:51:54.973791Z","caller":"traceutil/trace.go:171","msg":"trace[270708050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25469; }","duration":"133.235146ms","start":"2026-07-13T20:51:54.840542Z","end":"2026-07-13T20:51:54.973777Z","steps":["trace[270708050] 'agreement among raft nodes before linearized reading' (duration: 132.883668ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:54.973807Z","caller":"traceutil/trace.go:171","msg":"trace[711283004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25469; }","duration":"119.363399ms","start":"2026-07-13T20:51:54.854426Z","end":"2026-07-13T20:51:54.973789Z","steps":["trace[711283004] 'agreement among raft nodes before linearized reading' (duration: 119.112993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:51:54.973728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.911601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-13T20:51:54.973943Z","caller":"traceutil/trace.go:171","msg":"trace[358393061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25469; }","duration":"122.148007ms","start":"2026-07-13T20:51:54.851781Z","end":"2026-07-13T20:51:54.973929Z","steps":["trace[358393061] 'agreement among raft nodes before linearized reading' (duration: 121.825339ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:04.564481Z","caller":"traceutil/trace.go:171","msg":"trace[1026638416] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"109.93372ms","start":"2026-07-13T20:52:04.454526Z","end":"2026-07-13T20:52:04.56446Z","steps":["trace[1026638416] 'process raft request' (duration: 109.608462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:11.401688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.137614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:52:11.40191Z","caller":"traceutil/trace.go:171","msg":"trace[57115336] linearizableReadLoop","detail":"{readStateIndex:26428; appliedIndex:26426; }","duration":"179.311714ms","start":"2026-07-13T20:52:11.222581Z","end":"2026-07-13T20:52:11.401893Z","steps":["trace[57115336] 'read index received' (duration: 26.798631ms)","trace[57115336] 'applied index is now lower than readState.Index' (duration: 152.512113ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:52:11.401984Z","caller":"traceutil/trace.go:171","msg":"trace[1839741026] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"186.466607ms","start":"2026-07-13T20:52:11.215507Z","end":"2026-07-13T20:52:11.401974Z","steps":["trace[1839741026] 'process raft request' (duration: 186.290963ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.402052Z","caller":"traceutil/trace.go:171","msg":"trace[65795420] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"187.005721ms","start":"2026-07-13T20:52:11.215011Z","end":"2026-07-13T20:52:11.402017Z","steps":["trace[65795420] 'process raft request' (duration: 34.438857ms)","trace[65795420] 'compare' (duration: 151.795776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:52:11.402286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.705254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T20:52:11.40232Z","caller":"traceutil/trace.go:171","msg":"trace[567505660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25599; }","duration":"179.739194ms","start":"2026-07-13T20:52:11.222568Z","end":"2026-07-13T20:52:11.402307Z","steps":["trace[567505660] 'agreement among raft nodes before linearized reading' (duration: 179.594621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:11.412026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.787794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T20:52:11.41207Z","caller":"traceutil/trace.go:171","msg":"trace[464704599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25600; }","duration":"183.841246ms","start":"2026-07-13T20:52:11.228211Z","end":"2026-07-13T20:52:11.412052Z","steps":["trace[464704599] 'agreement among raft nodes before linearized reading' (duration: 183.738173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:11.412275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.166476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-13T20:52:11.413949Z","caller":"traceutil/trace.go:171","msg":"trace[904376353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25600; }","duration":"186.886078ms","start":"2026-07-13T20:52:11.227046Z","end":"2026-07-13T20:52:11.413932Z","steps":["trace[904376353] 'agreement among raft nodes before linearized reading' (duration: 185.062214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:11.417596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.75807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T20:52:11.41764Z","caller":"traceutil/trace.go:171","msg":"trace[2129756118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25600; }","duration":"194.823472ms","start":"2026-07-13T20:52:11.222806Z","end":"2026-07-13T20:52:11.417629Z","steps":["trace[2129756118] 'agreement among raft nodes before linearized reading' (duration: 189.004031ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.546912Z","caller":"traceutil/trace.go:171","msg":"trace[1394457694] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"139.725664ms","start":"2026-07-13T20:52:11.40716Z","end":"2026-07-13T20:52:11.546886Z","steps":["trace[1394457694] 'process raft request' (duration: 139.576ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.571648Z","caller":"traceutil/trace.go:171","msg":"trace[1484577602] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"156.183063ms","start":"2026-07-13T20:52:11.415442Z","end":"2026-07-13T20:52:11.571625Z","steps":["trace[1484577602] 'process raft request' (duration: 156.033029ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.571891Z","caller":"traceutil/trace.go:171","msg":"trace[33721710] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"151.482739ms","start":"2026-07-13T20:52:11.420388Z","end":"2026-07-13T20:52:11.571871Z","steps":["trace[33721710] 'process raft request' (duration: 151.210312ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.57204Z","caller":"traceutil/trace.go:171","msg":"trace[306556029] linearizableReadLoop","detail":"{readStateIndex:26431; appliedIndex:26429; }","duration":"153.98657ms","start":"2026-07-13T20:52:11.418045Z","end":"2026-07-13T20:52:11.572032Z","steps":["trace[306556029] 'read index received' (duration: 128.596843ms)","trace[306556029] 'applied index is now lower than readState.Index' (duration: 25.389087ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:52:11.572109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.051951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:52:11.572126Z","caller":"traceutil/trace.go:171","msg":"trace[1284235687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25604; }","duration":"154.108893ms","start":"2026-07-13T20:52:11.418011Z","end":"2026-07-13T20:52:11.572119Z","steps":["trace[1284235687] 'agreement among raft nodes before linearized reading' (duration: 154.045211ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:11.572196Z","caller":"traceutil/trace.go:171","msg":"trace[735220080] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"151.751215ms","start":"2026-07-13T20:52:11.420436Z","end":"2026-07-13T20:52:11.572188Z","steps":["trace[735220080] 'process raft request' (duration: 151.354256ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.578588Z","caller":"traceutil/trace.go:171","msg":"trace[1085282911] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"163.46579ms","start":"2026-07-13T20:52:15.415096Z","end":"2026-07-13T20:52:15.578561Z","steps":["trace[1085282911] 'process raft request' (duration: 163.318256ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.599339Z","caller":"traceutil/trace.go:171","msg":"trace[191335920] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"173.974285ms","start":"2026-07-13T20:52:15.425337Z","end":"2026-07-13T20:52:15.599311Z","steps":["trace[191335920] 'process raft request' (duration: 173.878073ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.599885Z","caller":"traceutil/trace.go:171","msg":"trace[320660325] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"180.493713ms","start":"2026-07-13T20:52:15.418804Z","end":"2026-07-13T20:52:15.599297Z","steps":["trace[320660325] 'process raft request' (duration: 180.283118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:15.801911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.572994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T20:52:15.802181Z","caller":"traceutil/trace.go:171","msg":"trace[1010923066] linearizableReadLoop","detail":"{readStateIndex:26466; appliedIndex:26462; }","duration":"165.926279ms","start":"2026-07-13T20:52:15.636238Z","end":"2026-07-13T20:52:15.802165Z","steps":["trace[1010923066] 'read index received' (duration: 46.012977ms)","trace[1010923066] 'applied index is now lower than readState.Index' (duration: 119.912462ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:52:15.802277Z","caller":"traceutil/trace.go:171","msg":"trace[518381797] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"197.520547ms","start":"2026-07-13T20:52:15.604745Z","end":"2026-07-13T20:52:15.802266Z","steps":["trace[518381797] 'process raft request' (duration: 77.515013ms)","trace[518381797] 'compare' (duration: 118.897307ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:52:15.802608Z","caller":"traceutil/trace.go:171","msg":"trace[468188913] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"195.6108ms","start":"2026-07-13T20:52:15.606945Z","end":"2026-07-13T20:52:15.802556Z","steps":["trace[468188913] 'process raft request' (duration: 195.154059ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.802596Z","caller":"traceutil/trace.go:171","msg":"trace[777947890] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"196.108522ms","start":"2026-07-13T20:52:15.606443Z","end":"2026-07-13T20:52:15.802552Z","steps":["trace[777947890] 'process raft request' (duration: 195.568019ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.802745Z","caller":"traceutil/trace.go:171","msg":"trace[2092897037] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"196.182434ms","start":"2026-07-13T20:52:15.606523Z","end":"2026-07-13T20:52:15.802705Z","steps":["trace[2092897037] 'process raft request' (duration: 195.545499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:15.802836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.569664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-54b648dbfc-72cp2\" ","response":"range_response_count:1 size:8865"} {"level":"info","ts":"2026-07-13T20:52:15.80296Z","caller":"traceutil/trace.go:171","msg":"trace[1216725425] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-54b648dbfc-72cp2; range_end:; response_count:1; response_revision:25637; }","duration":"166.697207ms","start":"2026-07-13T20:52:15.636231Z","end":"2026-07-13T20:52:15.802928Z","steps":["trace[1216725425] 'agreement among raft nodes before linearized reading' (duration: 166.351269ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:15.802967Z","caller":"traceutil/trace.go:171","msg":"trace[380247262] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"140.313438ms","start":"2026-07-13T20:52:15.66234Z","end":"2026-07-13T20:52:15.802653Z","steps":["trace[380247262] 'process raft request' (duration: 139.790245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:52:15.803429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.683013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-54b648dbfc-72cp2.18c1f4996cceecfb\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-13T20:52:15.804065Z","caller":"traceutil/trace.go:171","msg":"trace[1431310996] range","detail":"{range_begin:/registry/events/openstack/magnum-api-54b648dbfc-72cp2.18c1f4996cceecfb; range_end:; response_count:1; response_revision:25637; }","duration":"166.365331ms","start":"2026-07-13T20:52:15.637677Z","end":"2026-07-13T20:52:15.804043Z","steps":["trace[1431310996] 'agreement among raft nodes before linearized reading' (duration: 164.985967ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:16.013178Z","caller":"traceutil/trace.go:171","msg":"trace[1355049146] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"199.569086ms","start":"2026-07-13T20:52:15.813592Z","end":"2026-07-13T20:52:16.013161Z","steps":["trace[1355049146] 'process raft request' (duration: 199.430782ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:16.01357Z","caller":"traceutil/trace.go:171","msg":"trace[1193809962] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"153.115847ms","start":"2026-07-13T20:52:15.860369Z","end":"2026-07-13T20:52:16.013485Z","steps":["trace[1193809962] 'process raft request' (duration: 152.988854ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:16.013458Z","caller":"traceutil/trace.go:171","msg":"trace[425228659] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"155.298221ms","start":"2026-07-13T20:52:15.858154Z","end":"2026-07-13T20:52:16.013452Z","steps":["trace[425228659] 'process raft request' (duration: 154.967133ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:52:51.047934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22983} {"level":"info","ts":"2026-07-13T20:52:51.177499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22983,"took":"126.367098ms","hash":2934317973,"current-db-size-bytes":62185472,"current-db-size":"62 MB","current-db-size-in-use-bytes":37957632,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-13T20:52:51.177581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2934317973,"revision":22983,"compact-revision":20126} {"level":"warn","ts":"2026-07-13T20:54:04.829383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394681790947350,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T20:54:05.127434Z","caller":"traceutil/trace.go:171","msg":"trace[682466770] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"798.980262ms","start":"2026-07-13T20:54:04.328429Z","end":"2026-07-13T20:54:05.127409Z","steps":["trace[682466770] 'process raft request' (duration: 798.830728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.127587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.328418Z","time spent":"799.093134ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:05.530804Z","caller":"traceutil/trace.go:171","msg":"trace[1403514477] transaction","detail":"{read_only:false; response_revision:26749; number_of_response:1; }","duration":"1.195944653s","start":"2026-07-13T20:54:04.334758Z","end":"2026-07-13T20:54:05.530702Z","steps":["trace[1403514477] 'process raft request' (duration: 1.195714187s)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.530869Z","caller":"traceutil/trace.go:171","msg":"trace[351827728] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"816.781065ms","start":"2026-07-13T20:54:04.714037Z","end":"2026-07-13T20:54:05.530818Z","steps":["trace[351827728] 'process raft request' (duration: 816.654632ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.530931Z","caller":"traceutil/trace.go:171","msg":"trace[1702027365] linearizableReadLoop","detail":"{readStateIndex:27610; appliedIndex:27603; }","duration":"1.202218455s","start":"2026-07-13T20:54:04.328699Z","end":"2026-07-13T20:54:05.530918Z","steps":["trace[1702027365] 'read index received' (duration: 798.610713ms)","trace[1702027365] 'applied index is now lower than readState.Index' (duration: 403.606192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:54:05.530979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.334733Z","time spent":"1.196159197s","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:54:05.531063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.714012Z","time spent":"816.942489ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:05.530819Z","caller":"traceutil/trace.go:171","msg":"trace[825427084] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"790.65324ms","start":"2026-07-13T20:54:04.740145Z","end":"2026-07-13T20:54:05.530798Z","steps":["trace[825427084] 'process raft request' (duration: 790.575128ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.531208Z","caller":"traceutil/trace.go:171","msg":"trace[1557738323] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"1.195947383s","start":"2026-07-13T20:54:04.33479Z","end":"2026-07-13T20:54:05.530737Z","steps":["trace[1557738323] 'process raft request' (duration: 1.19581443s)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.531252Z","caller":"traceutil/trace.go:171","msg":"trace[726711475] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"621.190606ms","start":"2026-07-13T20:54:04.910036Z","end":"2026-07-13T20:54:05.531227Z","steps":["trace[726711475] 'process raft request' (duration: 620.716853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.531238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.740125Z","time spent":"791.024291ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:54:05.531346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.33477Z","time spent":"1.196486307s","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:54:05.531358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202638886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-13T20:54:05.53138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.910014Z","time spent":"621.272888ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21400,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:05.531398Z","caller":"traceutil/trace.go:171","msg":"trace[1956893307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26755; }","duration":"1.202708747s","start":"2026-07-13T20:54:04.328678Z","end":"2026-07-13T20:54:05.531387Z","steps":["trace[1956893307] 'agreement among raft nodes before linearized reading' (duration: 1.202553874s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.531431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.328669Z","time spent":"1.202753768s","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-13T20:54:05.53081Z","caller":"traceutil/trace.go:171","msg":"trace[953960075] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"1.088205147s","start":"2026-07-13T20:54:04.442568Z","end":"2026-07-13T20:54:05.530773Z","steps":["trace[953960075] 'process raft request' (duration: 1.088066653s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.531506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.442543Z","time spent":"1.088934305s","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:05.531639Z","caller":"traceutil/trace.go:171","msg":"trace[842813289] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"1.084211749s","start":"2026-07-13T20:54:04.446548Z","end":"2026-07-13T20:54:05.530759Z","steps":["trace[842813289] 'process raft request' (duration: 1.084114217s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.531748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.446529Z","time spent":"1.085191184s","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:54:05.569235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173828806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.569314Z","caller":"traceutil/trace.go:171","msg":"trace[1875617200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26756; }","duration":"1.173918159s","start":"2026-07-13T20:54:04.395378Z","end":"2026-07-13T20:54:05.569296Z","steps":["trace[1875617200] 'agreement among raft nodes before linearized reading' (duration: 1.173777585s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.569402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.395316Z","time spent":"1.174076132s","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:54:05.56955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.630969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21418"} {"level":"info","ts":"2026-07-13T20:54:05.569696Z","caller":"traceutil/trace.go:171","msg":"trace[1713368413] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26756; }","duration":"505.905626ms","start":"2026-07-13T20:54:05.063771Z","end":"2026-07-13T20:54:05.569676Z","steps":["trace[1713368413] 'agreement among raft nodes before linearized reading' (duration: 505.451385ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.569914Z","caller":"traceutil/trace.go:171","msg":"trace[1685111797] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"437.970946ms","start":"2026-07-13T20:54:05.131903Z","end":"2026-07-13T20:54:05.569874Z","steps":["trace[1685111797] 'process raft request' (duration: 437.097655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.569933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.568823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.570029Z","caller":"traceutil/trace.go:171","msg":"trace[923089075] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:26756; }","duration":"343.705956ms","start":"2026-07-13T20:54:05.226288Z","end":"2026-07-13T20:54:05.569994Z","steps":["trace[923089075] 'agreement among raft nodes before linearized reading' (duration: 343.532552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.570079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.226252Z","time spent":"343.817009ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:54:05.5701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.864269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-13T20:54:05.570189Z","caller":"traceutil/trace.go:171","msg":"trace[1860219260] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26756; }","duration":"878.014482ms","start":"2026-07-13T20:54:04.692155Z","end":"2026-07-13T20:54:05.57017Z","steps":["trace[1860219260] 'agreement among raft nodes before linearized reading' (duration: 877.747956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.570253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.692132Z","time spent":"878.109704ms","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-13T20:54:05.570376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.612766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:54:05.570387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06319685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.570416Z","caller":"traceutil/trace.go:171","msg":"trace[2051925430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26756; }","duration":"229.650937ms","start":"2026-07-13T20:54:05.340755Z","end":"2026-07-13T20:54:05.570406Z","steps":["trace[2051925430] 'agreement among raft nodes before linearized reading' (duration: 229.589945ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.570452Z","caller":"traceutil/trace.go:171","msg":"trace[1940629267] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26756; }","duration":"1.063267451s","start":"2026-07-13T20:54:04.507149Z","end":"2026-07-13T20:54:05.570416Z","steps":["trace[1940629267] 'agreement among raft nodes before linearized reading' (duration: 1.063205649s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.570496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.507138Z","time spent":"1.063344714s","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-07-13T20:54:05.570568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.270947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-13T20:54:05.570613Z","caller":"traceutil/trace.go:171","msg":"trace[460904480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26756; }","duration":"588.313628ms","start":"2026-07-13T20:54:04.982286Z","end":"2026-07-13T20:54:05.5706Z","steps":["trace[460904480] 'agreement among raft nodes before linearized reading' (duration: 588.199525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.570646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.982221Z","time spent":"588.4195ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-13T20:54:05.570814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058624658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21418"} {"level":"warn","ts":"2026-07-13T20:54:05.570821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.651969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21418"} {"level":"warn","ts":"2026-07-13T20:54:05.56994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.063752Z","time spent":"506.172272ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21441,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-07-13T20:54:05.570872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.219847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21418"} {"level":"warn","ts":"2026-07-13T20:54:05.570815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.009823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:54:05.570899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.300934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.570912Z","caller":"traceutil/trace.go:171","msg":"trace[1043898058] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26756; }","duration":"390.298349ms","start":"2026-07-13T20:54:05.180604Z","end":"2026-07-13T20:54:05.570903Z","steps":["trace[1043898058] 'agreement among raft nodes before linearized reading' (duration: 390.234567ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.570925Z","caller":"traceutil/trace.go:171","msg":"trace[590533778] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26756; }","duration":"627.353945ms","start":"2026-07-13T20:54:04.943564Z","end":"2026-07-13T20:54:05.570918Z","steps":["trace[590533778] 'agreement among raft nodes before linearized reading' (duration: 627.318044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.570948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.180583Z","time spent":"390.358371ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21441,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-07-13T20:54:05.569803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.191105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.570855Z","caller":"traceutil/trace.go:171","msg":"trace[1039070666] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26756; }","duration":"1.05869983s","start":"2026-07-13T20:54:04.512145Z","end":"2026-07-13T20:54:05.570845Z","steps":["trace[1039070666] 'agreement among raft nodes before linearized reading' (duration: 1.058595278s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.571019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.485862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T20:54:05.571049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.512125Z","time spent":"1.058916405s","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21441,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-07-13T20:54:05.571042Z","caller":"traceutil/trace.go:171","msg":"trace[1817991301] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26756; }","duration":"510.436755ms","start":"2026-07-13T20:54:05.060577Z","end":"2026-07-13T20:54:05.571013Z","steps":["trace[1817991301] 'agreement among raft nodes before linearized reading' (duration: 509.191115ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.571066Z","caller":"traceutil/trace.go:171","msg":"trace[1919926185] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26756; }","duration":"406.596924ms","start":"2026-07-13T20:54:05.164452Z","end":"2026-07-13T20:54:05.571049Z","steps":["trace[1919926185] 'agreement among raft nodes before linearized reading' (duration: 406.494792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.571111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.164428Z","time spent":"406.672616ms","remote":"127.0.0.1:40716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:54:05.571137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.060559Z","time spent":"510.539379ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-07-13T20:54:05.570861Z","caller":"traceutil/trace.go:171","msg":"trace[1204636522] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26756; }","duration":"612.726501ms","start":"2026-07-13T20:54:04.958123Z","end":"2026-07-13T20:54:05.570849Z","steps":["trace[1204636522] 'agreement among raft nodes before linearized reading' (duration: 612.654789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.571206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.958103Z","time spent":"613.093639ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21441,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-07-13T20:54:05.570951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.943547Z","time spent":"627.399107ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-07-13T20:54:05.571325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.007858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:05.571363Z","caller":"traceutil/trace.go:171","msg":"trace[391074146] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26756; }","duration":"438.107331ms","start":"2026-07-13T20:54:05.133242Z","end":"2026-07-13T20:54:05.57135Z","steps":["trace[391074146] 'agreement among raft nodes before linearized reading' (duration: 438.050059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.571396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.133221Z","time spent":"438.167251ms","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:54:05.570108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.131883Z","time spent":"438.071468ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:05.570916Z","caller":"traceutil/trace.go:171","msg":"trace[346057784] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26756; }","duration":"395.112215ms","start":"2026-07-13T20:54:05.175795Z","end":"2026-07-13T20:54:05.570907Z","steps":["trace[346057784] 'agreement among raft nodes before linearized reading' (duration: 394.971432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.571617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.175714Z","time spent":"395.812182ms","remote":"127.0.0.1:35412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-07-13T20:54:05.572812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076569574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-13T20:54:05.572846Z","caller":"traceutil/trace.go:171","msg":"trace[1594233815] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26756; }","duration":"1.076643766s","start":"2026-07-13T20:54:04.496195Z","end":"2026-07-13T20:54:05.572839Z","steps":["trace[1594233815] 'agreement among raft nodes before linearized reading' (duration: 1.075068988s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.572867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:04.496175Z","time spent":"1.076686807s","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2773,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:54:05.767633Z","caller":"traceutil/trace.go:171","msg":"trace[680337275] linearizableReadLoop","detail":"{readStateIndex:27614; appliedIndex:27612; }","duration":"192.224278ms","start":"2026-07-13T20:54:05.575388Z","end":"2026-07-13T20:54:05.767612Z","steps":["trace[680337275] 'read index received' (duration: 192.094235ms)","trace[680337275] 'applied index is now lower than readState.Index' (duration: 128.853µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:54:05.767704Z","caller":"traceutil/trace.go:171","msg":"trace[1724835373] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"230.944469ms","start":"2026-07-13T20:54:05.536718Z","end":"2026-07-13T20:54:05.767662Z","steps":["trace[1724835373] 'process raft request' (duration: 230.706383ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.767737Z","caller":"traceutil/trace.go:171","msg":"trace[732697191] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"207.181512ms","start":"2026-07-13T20:54:05.560522Z","end":"2026-07-13T20:54:05.767704Z","steps":["trace[732697191] 'process raft request' (duration: 207.046838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.767995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.583097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-13T20:54:05.768046Z","caller":"traceutil/trace.go:171","msg":"trace[99942360] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26758; }","duration":"192.654708ms","start":"2026-07-13T20:54:05.575382Z","end":"2026-07-13T20:54:05.768036Z","steps":["trace[99942360] 'agreement among raft nodes before linearized reading' (duration: 192.420913ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.960151Z","caller":"traceutil/trace.go:171","msg":"trace[350733379] linearizableReadLoop","detail":"{readStateIndex:27615; appliedIndex:27614; }","duration":"190.848085ms","start":"2026-07-13T20:54:05.769269Z","end":"2026-07-13T20:54:05.960117Z","steps":["trace[350733379] 'read index received' (duration: 101.620388ms)","trace[350733379] 'applied index is now lower than readState.Index' (duration: 89.224487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:54:05.960405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.115361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18393"} {"level":"info","ts":"2026-07-13T20:54:05.960448Z","caller":"traceutil/trace.go:171","msg":"trace[1059842954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26759; }","duration":"191.205394ms","start":"2026-07-13T20:54:05.769232Z","end":"2026-07-13T20:54:05.960437Z","steps":["trace[1059842954] 'agreement among raft nodes before linearized reading' (duration: 191.048801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:54:05.960686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.955988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T20:54:05.960725Z","caller":"traceutil/trace.go:171","msg":"trace[1792563172] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26759; }","duration":"155.999259ms","start":"2026-07-13T20:54:05.804714Z","end":"2026-07-13T20:54:05.960714Z","steps":["trace[1792563172] 'agreement among raft nodes before linearized reading' (duration: 155.934287ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:05.960172Z","caller":"traceutil/trace.go:171","msg":"trace[1363830873] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"382.1304ms","start":"2026-07-13T20:54:05.578001Z","end":"2026-07-13T20:54:05.960132Z","steps":["trace[1363830873] 'process raft request' (duration: 292.895633ms)","trace[1363830873] 'compare' (duration: 88.690353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:54:05.961739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:54:05.577986Z","time spent":"383.325329ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:54:06.14823Z","caller":"traceutil/trace.go:171","msg":"trace[832875255] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"128.416579ms","start":"2026-07-13T20:54:06.019785Z","end":"2026-07-13T20:54:06.148202Z","steps":["trace[832875255] 'process raft request' (duration: 50.025975ms)","trace[832875255] 'compare' (duration: 78.312202ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:54:06.185428Z","caller":"traceutil/trace.go:171","msg":"trace[23057015] linearizableReadLoop","detail":"{readStateIndex:27618; appliedIndex:27616; }","duration":"164.862584ms","start":"2026-07-13T20:54:06.020536Z","end":"2026-07-13T20:54:06.185399Z","steps":["trace[23057015] 'read index received' (duration: 49.284128ms)","trace[23057015] 'applied index is now lower than readState.Index' (duration: 115.577506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:54:06.185575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.018948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T20:54:06.18567Z","caller":"traceutil/trace.go:171","msg":"trace[678672528] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26762; }","duration":"165.146331ms","start":"2026-07-13T20:54:06.020513Z","end":"2026-07-13T20:54:06.185659Z","steps":["trace[678672528] 'agreement among raft nodes before linearized reading' (duration: 164.987187ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:06.186065Z","caller":"traceutil/trace.go:171","msg":"trace[1823918107] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"165.96891ms","start":"2026-07-13T20:54:06.020085Z","end":"2026-07-13T20:54:06.186054Z","steps":["trace[1823918107] 'process raft request' (duration: 165.159371ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:54:07.268879Z","caller":"traceutil/trace.go:171","msg":"trace[41978292] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"128.510451ms","start":"2026-07-13T20:54:07.140343Z","end":"2026-07-13T20:54:07.268854Z","steps":["trace[41978292] 'process raft request' (duration: 128.357047ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:57:51.054375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26073} {"level":"info","ts":"2026-07-13T20:57:51.197898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26073,"took":"139.160709ms","hash":2347241580,"current-db-size-bytes":62185472,"current-db-size":"62 MB","current-db-size-in-use-bytes":36364288,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-13T20:57:51.197951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2347241580,"revision":26073,"compact-revision":22983} {"level":"info","ts":"2026-07-13T20:58:25.08822Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T20:58:25.092372Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-13T20:58:25.092458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-13T21:02:51.063114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28835} {"level":"info","ts":"2026-07-13T21:02:51.203159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28835,"took":"134.778533ms","hash":440429002,"current-db-size-bytes":62185472,"current-db-size":"62 MB","current-db-size-in-use-bytes":32464896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-13T21:02:51.203231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":440429002,"revision":28835,"compact-revision":26073} {"level":"info","ts":"2026-07-13T21:07:51.069741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31133} {"level":"info","ts":"2026-07-13T21:07:51.194936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31133,"took":"121.433634ms","hash":1496677360,"current-db-size-bytes":62185472,"current-db-size":"62 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T21:07:51.195007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1496677360,"revision":31133,"compact-revision":28835} {"level":"info","ts":"2026-07-13T21:12:51.076462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33447} {"level":"info","ts":"2026-07-13T21:12:51.235821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33447,"took":"152.535711ms","hash":3279522114,"current-db-size-bytes":63229952,"current-db-size":"63 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-13T21:12:51.235896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3279522114,"revision":33447,"compact-revision":31133} {"level":"info","ts":"2026-07-13T21:17:51.085682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35747} {"level":"info","ts":"2026-07-13T21:17:51.218235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35747,"took":"129.755247ms","hash":4138378344,"current-db-size-bytes":63229952,"current-db-size":"63 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T21:17:51.218283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4138378344,"revision":35747,"compact-revision":33447} {"level":"info","ts":"2026-07-13T21:19:34.698929Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T21:19:34.704009Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-13T21:19:34.704227Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}