{"level":"warn","ts":"2026-07-23T23:52:16.773682Z","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-23T23:52:16.773793Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-23T23:52:16.773871Z","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-23T23:52:16.773887Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-23T23:52:16.773906Z","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-23T23:52:16.774603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-23T23:52:16.774677Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-23T23:52:16.780595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.709173ms"} {"level":"info","ts":"2026-07-23T23:52:16.786333Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-23T23:52:16.786428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:52:16.786448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-23T23:52:16.786467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:52:16.78648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-23T23:52:16.78652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-23T23:52:16.793578Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:52:16.795285Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:52:16.796523Z","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-23T23:52:16.798841Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:52:16.799069Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:52:16.799247Z","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-23T23:52:16.799391Z","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-23T23:52:16.799419Z","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-23T23:52:16.80062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-23T23:52:16.802246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-23T23:52:16.805534Z","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-23T23:52:16.805695Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-23T23:52:16.805783Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-23T23:52:16.805856Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:52:16.805895Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:52:17.187654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:52:17.187762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:52:17.187795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-23T23:52:17.187819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:52:17.18783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-23T23:52:17.187849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-23T23:52:17.187871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-23T23:52:17.189326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:17.189572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:52:17.189644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:52:17.189676Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:52:17.19019Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:52:17.190231Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:52:17.190413Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:17.19053Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:17.190561Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:52:17.192885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-07-23T23:52:17.193254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-23T23:52:27.331294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.553622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:52:27.331444Z","caller":"traceutil/trace.go:171","msg":"trace[284166960] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"374.210836ms","start":"2026-07-23T23:52:26.957211Z","end":"2026-07-23T23:52:27.331422Z","steps":["trace[284166960] 'process raft request' (duration: 139.913919ms)","trace[284166960] 'compare' (duration: 233.375739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:52:27.331538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:52:26.957185Z","time spent":"374.312018ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:52:27.663609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.139105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T23:52:27.66372Z","caller":"traceutil/trace.go:171","msg":"trace[1340237061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"138.37706ms","start":"2026-07-23T23:52:27.525318Z","end":"2026-07-23T23:52:27.663695Z","steps":["trace[1340237061] 'range keys from in-memory index tree' (duration: 138.003063ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:59.775523Z","caller":"traceutil/trace.go:171","msg":"trace[888513871] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"142.130451ms","start":"2026-07-23T23:52:59.63336Z","end":"2026-07-23T23:52:59.775491Z","steps":["trace[888513871] 'process raft request' (duration: 141.584141ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:00.596849Z","caller":"traceutil/trace.go:171","msg":"trace[1606286297] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"320.701652ms","start":"2026-07-23T23:53:00.276116Z","end":"2026-07-23T23:53:00.596817Z","steps":["trace[1606286297] 'read index received' (duration: 319.637442ms)","trace[1606286297] 'applied index is now lower than readState.Index' (duration: 1.063309ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:00.596913Z","caller":"traceutil/trace.go:171","msg":"trace[756955836] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"338.328015ms","start":"2026-07-23T23:53:00.258539Z","end":"2026-07-23T23:53:00.596867Z","steps":["trace[756955836] 'process raft request' (duration: 337.577031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:00.597012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.874586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T23:53:00.597105Z","caller":"traceutil/trace.go:171","msg":"trace[2055736677] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:591; }","duration":"321.015389ms","start":"2026-07-23T23:53:00.276071Z","end":"2026-07-23T23:53:00.597086Z","steps":["trace[2055736677] 'agreement among raft nodes before linearized reading' (duration: 320.871975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:00.597167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:00.276053Z","time spent":"321.09951ms","remote":"127.0.0.1:59924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-07-23T23:53:00.597164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:00.258501Z","time spent":"338.50504ms","remote":"127.0.0.1:59986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:53:00.846512Z","caller":"traceutil/trace.go:171","msg":"trace[291820526] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"213.88099ms","start":"2026-07-23T23:53:00.632605Z","end":"2026-07-23T23:53:00.846486Z","steps":["trace[291820526] 'process raft request' (duration: 178.816006ms)","trace[291820526] 'compare' (duration: 34.704596ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:00.846738Z","caller":"traceutil/trace.go:171","msg":"trace[1964064699] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"189.780144ms","start":"2026-07-23T23:53:00.65669Z","end":"2026-07-23T23:53:00.84647Z","steps":["trace[1964064699] 'process raft request' (duration: 189.724743ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:45.514663Z","caller":"traceutil/trace.go:171","msg":"trace[427718981] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"110.091683ms","start":"2026-07-23T23:53:45.404549Z","end":"2026-07-23T23:53:45.514641Z","steps":["trace[427718981] 'read index received' (duration: 86.858843ms)","trace[427718981] 'applied index is now lower than readState.Index' (duration: 23.23139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:45.514959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.348958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:53:45.515085Z","caller":"traceutil/trace.go:171","msg":"trace[1401509057] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1157; }","duration":"110.579922ms","start":"2026-07-23T23:53:45.404478Z","end":"2026-07-23T23:53:45.515058Z","steps":["trace[1401509057] 'agreement among raft nodes before linearized reading' (duration: 110.281267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:42.453982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.338017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:54:42.454132Z","caller":"traceutil/trace.go:171","msg":"trace[297003770] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"173.126851ms","start":"2026-07-23T23:54:42.28097Z","end":"2026-07-23T23:54:42.454096Z","steps":["trace[297003770] 'process raft request' (duration: 45.311604ms)","trace[297003770] 'compare' (duration: 126.619544ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:14.753856Z","caller":"traceutil/trace.go:171","msg":"trace[1226910727] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"129.804345ms","start":"2026-07-23T23:55:14.624014Z","end":"2026-07-23T23:55:14.753818Z","steps":["trace[1226910727] 'process raft request' (duration: 125.015923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:55:20.83358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.536589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:55:20.833692Z","caller":"traceutil/trace.go:171","msg":"trace[1736016071] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"148.110969ms","start":"2026-07-23T23:55:20.685563Z","end":"2026-07-23T23:55:20.833674Z","steps":["trace[1736016071] 'read index received' (duration: 17.009018ms)","trace[1736016071] 'applied index is now lower than readState.Index' (duration: 131.100591ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:20.833786Z","caller":"traceutil/trace.go:171","msg":"trace[1663045463] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"186.319017ms","start":"2026-07-23T23:55:20.647454Z","end":"2026-07-23T23:55:20.833773Z","steps":["trace[1663045463] 'process raft request' (duration: 55.157985ms)","trace[1663045463] 'compare' (duration: 130.293984ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:55:20.833962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.357573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:55:20.834069Z","caller":"traceutil/trace.go:171","msg":"trace[549925082] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2137; }","duration":"148.540127ms","start":"2026-07-23T23:55:20.685504Z","end":"2026-07-23T23:55:20.834044Z","steps":["trace[549925082] 'agreement among raft nodes before linearized reading' (duration: 148.308992ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:16.215381Z","caller":"traceutil/trace.go:171","msg":"trace[792393911] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"140.131867ms","start":"2026-07-23T23:56:16.07522Z","end":"2026-07-23T23:56:16.215352Z","steps":["trace[792393911] 'process raft request' (duration: 139.928453ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:16.216196Z","caller":"traceutil/trace.go:171","msg":"trace[454378362] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"140.247451ms","start":"2026-07-23T23:56:16.075909Z","end":"2026-07-23T23:56:16.216156Z","steps":["trace[454378362] 'process raft request' (duration: 140.012815ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:56:51.106533Z","caller":"traceutil/trace.go:171","msg":"trace[906338730] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"123.686533ms","start":"2026-07-23T23:56:50.982812Z","end":"2026-07-23T23:56:51.106499Z","steps":["trace[906338730] 'process raft request' (duration: 123.50881ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:00:56.993767Z","caller":"traceutil/trace.go:171","msg":"trace[878007175] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"184.982114ms","start":"2026-07-24T00:00:56.808737Z","end":"2026-07-24T00:00:56.993719Z","steps":["trace[878007175] 'process raft request' (duration: 110.936644ms)","trace[878007175] 'compare' (duration: 73.799006ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:01:08.476274Z","caller":"traceutil/trace.go:171","msg":"trace[598999266] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"105.630832ms","start":"2026-07-24T00:01:08.370611Z","end":"2026-07-24T00:01:08.476242Z","steps":["trace[598999266] 'process raft request' (duration: 88.78174ms)","trace[598999266] 'compare' (duration: 16.616887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:01:09.034581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.503471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-07-24T00:01:09.034688Z","caller":"traceutil/trace.go:171","msg":"trace[985875181] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5295; }","duration":"168.707755ms","start":"2026-07-24T00:01:08.865956Z","end":"2026-07-24T00:01:09.034664Z","steps":["trace[985875181] 'range keys from in-memory index tree' (duration: 168.294116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:01:09.034854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.319478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-fzk4b\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-07-24T00:01:09.03496Z","caller":"traceutil/trace.go:171","msg":"trace[1979151382] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-fzk4b; range_end:; response_count:1; response_revision:5295; }","duration":"167.44374ms","start":"2026-07-24T00:01:08.867474Z","end":"2026-07-24T00:01:09.034918Z","steps":["trace[1979151382] 'range keys from in-memory index tree' (duration: 167.130104ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:01:16.914608Z","caller":"traceutil/trace.go:171","msg":"trace[492515324] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"231.064903ms","start":"2026-07-24T00:01:16.683512Z","end":"2026-07-24T00:01:16.914577Z","steps":["trace[492515324] 'process raft request' (duration: 230.852438ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:02:17.226404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-07-24T00:02:17.299353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"70.63755ms","hash":2429171379,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T00:02:17.299416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2429171379,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-07-24T00:07:17.234197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6034} {"level":"info","ts":"2026-07-24T00:07:17.335009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6034,"took":"98.036561ms","hash":2639844265,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":24133632,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-24T00:07:17.335072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639844265,"revision":6034,"compact-revision":2959} {"level":"info","ts":"2026-07-24T00:07:32.516171Z","caller":"traceutil/trace.go:171","msg":"trace[1234899928] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"146.506938ms","start":"2026-07-24T00:07:32.369628Z","end":"2026-07-24T00:07:32.516135Z","steps":["trace[1234899928] 'process raft request' (duration: 146.254354ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:07:33.936476Z","caller":"traceutil/trace.go:171","msg":"trace[885575893] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"100.669186ms","start":"2026-07-24T00:07:33.835764Z","end":"2026-07-24T00:07:33.936433Z","steps":["trace[885575893] 'process raft request' (duration: 66.166119ms)","trace[885575893] 'compare' (duration: 33.962297ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:08:01.926631Z","caller":"traceutil/trace.go:171","msg":"trace[1779349952] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"175.977397ms","start":"2026-07-24T00:08:01.750619Z","end":"2026-07-24T00:08:01.926597Z","steps":["trace[1779349952] 'process raft request' (duration: 144.641451ms)","trace[1779349952] 'compare' (duration: 31.185244ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:08:02.096481Z","caller":"traceutil/trace.go:171","msg":"trace[814130306] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"157.69988ms","start":"2026-07-24T00:08:01.938711Z","end":"2026-07-24T00:08:02.096411Z","steps":["trace[814130306] 'process raft request' (duration: 157.506647ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:08:14.873218Z","caller":"traceutil/trace.go:171","msg":"trace[477280574] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"133.45641ms","start":"2026-07-24T00:08:14.739733Z","end":"2026-07-24T00:08:14.87319Z","steps":["trace[477280574] 'process raft request' (duration: 133.247136ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:08:14.897688Z","caller":"traceutil/trace.go:171","msg":"trace[1136049896] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"149.815621ms","start":"2026-07-24T00:08:14.747825Z","end":"2026-07-24T00:08:14.89764Z","steps":["trace[1136049896] 'process raft request' (duration: 149.496535ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:08:15.777501Z","caller":"traceutil/trace.go:171","msg":"trace[836070618] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"170.336382ms","start":"2026-07-24T00:08:15.60711Z","end":"2026-07-24T00:08:15.777446Z","steps":["trace[836070618] 'process raft request' (duration: 169.964785ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:08:31.655894Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ae.wal"} {"level":"info","ts":"2026-07-24T00:10:05.981953Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:10:05.989548Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T00:10:05.989635Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T00:12:17.24068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8247} {"level":"info","ts":"2026-07-24T00:12:17.323977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8247,"took":"79.618734ms","hash":672922771,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":25915392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-24T00:12:17.324076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":672922771,"revision":8247,"compact-revision":6034} {"level":"info","ts":"2026-07-24T00:12:57.405801Z","caller":"traceutil/trace.go:171","msg":"trace[581083671] transaction","detail":"{read_only:false; response_revision:11163; number_of_response:1; }","duration":"110.953921ms","start":"2026-07-24T00:12:57.294822Z","end":"2026-07-24T00:12:57.405776Z","steps":["trace[581083671] 'process raft request' (duration: 99.003964ms)","trace[581083671] 'compare' (duration: 11.826185ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:17:17.250775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10739} {"level":"info","ts":"2026-07-24T00:17:17.333614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10739,"took":"79.533709ms","hash":395331444,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":27062272,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-24T00:17:17.33374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395331444,"revision":10739,"compact-revision":8247} {"level":"info","ts":"2026-07-24T00:19:46.46619Z","caller":"traceutil/trace.go:171","msg":"trace[2061853267] transaction","detail":"{read_only:false; response_revision:13883; number_of_response:1; }","duration":"196.490303ms","start":"2026-07-24T00:19:46.269678Z","end":"2026-07-24T00:19:46.466168Z","steps":["trace[2061853267] 'process raft request' (duration: 195.667318ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:46.466474Z","caller":"traceutil/trace.go:171","msg":"trace[170656000] linearizableReadLoop","detail":"{readStateIndex:14369; appliedIndex:14365; }","duration":"161.792495ms","start":"2026-07-24T00:19:46.30465Z","end":"2026-07-24T00:19:46.466443Z","steps":["trace[170656000] 'read index received' (duration: 512.56µs)","trace[170656000] 'applied index is now lower than readState.Index' (duration: 161.278875ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:19:46.466543Z","caller":"traceutil/trace.go:171","msg":"trace[1625284850] transaction","detail":"{read_only:false; response_revision:13885; number_of_response:1; }","duration":"193.587058ms","start":"2026-07-24T00:19:46.272946Z","end":"2026-07-24T00:19:46.466533Z","steps":["trace[1625284850] 'process raft request' (duration: 193.362264ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:46.466678Z","caller":"traceutil/trace.go:171","msg":"trace[1807540798] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"196.649157ms","start":"2026-07-24T00:19:46.270018Z","end":"2026-07-24T00:19:46.466667Z","steps":["trace[1807540798] 'process raft request' (duration: 196.107396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:46.466737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.08404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-pv2t2\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-07-24T00:19:46.466783Z","caller":"traceutil/trace.go:171","msg":"trace[1716408392] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-pv2t2; range_end:; response_count:1; response_revision:13885; }","duration":"162.195192ms","start":"2026-07-24T00:19:46.304575Z","end":"2026-07-24T00:19:46.46677Z","steps":["trace[1716408392] 'agreement among raft nodes before linearized reading' (duration: 161.980668ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:47.462464Z","caller":"traceutil/trace.go:171","msg":"trace[43404492] transaction","detail":"{read_only:false; response_revision:13892; number_of_response:1; }","duration":"124.571321ms","start":"2026-07-24T00:19:47.337866Z","end":"2026-07-24T00:19:47.462437Z","steps":["trace[43404492] 'process raft request' (duration: 124.358487ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:20:08.782654Z","caller":"traceutil/trace.go:171","msg":"trace[523319958] transaction","detail":"{read_only:false; response_revision:14195; number_of_response:1; }","duration":"179.746247ms","start":"2026-07-24T00:20:08.60287Z","end":"2026-07-24T00:20:08.782616Z","steps":["trace[523319958] 'process raft request' (duration: 179.537952ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:20:08.822652Z","caller":"traceutil/trace.go:171","msg":"trace[1568253533] linearizableReadLoop","detail":"{readStateIndex:14694; appliedIndex:14691; }","duration":"191.143042ms","start":"2026-07-24T00:20:08.631478Z","end":"2026-07-24T00:20:08.822621Z","steps":["trace[1568253533] 'read index received' (duration: 151.022481ms)","trace[1568253533] 'applied index is now lower than readState.Index' (duration: 40.119111ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:20:08.822716Z","caller":"traceutil/trace.go:171","msg":"trace[410186333] transaction","detail":"{read_only:false; response_revision:14197; number_of_response:1; }","duration":"217.39424ms","start":"2026-07-24T00:20:08.605293Z","end":"2026-07-24T00:20:08.822687Z","steps":["trace[410186333] 'process raft request' (duration: 217.275168ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:20:08.822714Z","caller":"traceutil/trace.go:171","msg":"trace[1532157765] transaction","detail":"{read_only:false; response_revision:14196; number_of_response:1; }","duration":"218.51544ms","start":"2026-07-24T00:20:08.604146Z","end":"2026-07-24T00:20:08.822662Z","steps":["trace[1532157765] 'process raft request' (duration: 218.222275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:20:08.822945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.444088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93f597c5-110b-4305-87ab-670f13f2fcb3\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-24T00:20:08.822995Z","caller":"traceutil/trace.go:171","msg":"trace[507423740] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93f597c5-110b-4305-87ab-670f13f2fcb3; range_end:; response_count:1; response_revision:14197; }","duration":"191.542979ms","start":"2026-07-24T00:20:08.631438Z","end":"2026-07-24T00:20:08.822981Z","steps":["trace[507423740] 'agreement among raft nodes before linearized reading' (duration: 191.301585ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:21:35.495409Z","caller":"traceutil/trace.go:171","msg":"trace[580289153] linearizableReadLoop","detail":"{readStateIndex:15529; appliedIndex:15528; }","duration":"187.085571ms","start":"2026-07-24T00:21:35.308291Z","end":"2026-07-24T00:21:35.495377Z","steps":["trace[580289153] 'read index received' (duration: 137.681428ms)","trace[580289153] 'applied index is now lower than readState.Index' (duration: 49.402243ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:21:35.495694Z","caller":"traceutil/trace.go:171","msg":"trace[660614534] transaction","detail":"{read_only:false; response_revision:15009; number_of_response:1; }","duration":"215.279369ms","start":"2026-07-24T00:21:35.280375Z","end":"2026-07-24T00:21:35.495654Z","steps":["trace[660614534] 'process raft request' (duration: 165.344937ms)","trace[660614534] 'compare' (duration: 49.489154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:21:35.496411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.837542ms","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-24T00:21:35.496516Z","caller":"traceutil/trace.go:171","msg":"trace[1063234485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15009; }","duration":"225.969344ms","start":"2026-07-24T00:21:35.270526Z","end":"2026-07-24T00:21:35.496496Z","steps":["trace[1063234485] 'agreement among raft nodes before linearized reading' (duration: 225.197048ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:21:35.529954Z","caller":"traceutil/trace.go:171","msg":"trace[864123539] transaction","detail":"{read_only:false; response_revision:15010; number_of_response:1; }","duration":"217.219257ms","start":"2026-07-24T00:21:35.312719Z","end":"2026-07-24T00:21:35.529938Z","steps":["trace[864123539] 'process raft request' (duration: 217.090075ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:21:35.530233Z","caller":"traceutil/trace.go:171","msg":"trace[1698497944] transaction","detail":"{read_only:false; response_revision:15011; number_of_response:1; }","duration":"131.101863ms","start":"2026-07-24T00:21:35.399095Z","end":"2026-07-24T00:21:35.530196Z","steps":["trace[1698497944] 'process raft request' (duration: 130.799387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:21:35.554759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.035053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T00:21:35.554828Z","caller":"traceutil/trace.go:171","msg":"trace[434799213] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15012; }","duration":"163.165035ms","start":"2026-07-24T00:21:35.391648Z","end":"2026-07-24T00:21:35.554813Z","steps":["trace[434799213] 'agreement among raft nodes before linearized reading' (duration: 163.004131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:21:35.554945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.776224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T00:21:35.554986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.853706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-24T00:21:35.555081Z","caller":"traceutil/trace.go:171","msg":"trace[937485157] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15013; }","duration":"156.015919ms","start":"2026-07-24T00:21:35.399046Z","end":"2026-07-24T00:21:35.555062Z","steps":["trace[937485157] 'agreement among raft nodes before linearized reading' (duration: 155.776195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:21:35.555133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.021319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-24T00:21:35.555173Z","caller":"traceutil/trace.go:171","msg":"trace[788403645] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15013; }","duration":"156.07447ms","start":"2026-07-24T00:21:35.399088Z","end":"2026-07-24T00:21:35.555162Z","steps":["trace[788403645] 'agreement among raft nodes before linearized reading' (duration: 155.931307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:21:35.555276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.492294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T00:21:35.555324Z","caller":"traceutil/trace.go:171","msg":"trace[516151049] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15013; }","duration":"150.584315ms","start":"2026-07-24T00:21:35.404726Z","end":"2026-07-24T00:21:35.555311Z","steps":["trace[516151049] 'agreement among raft nodes before linearized reading' (duration: 149.968442ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:21:35.555019Z","caller":"traceutil/trace.go:171","msg":"trace[960698312] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15013; }","duration":"155.882086ms","start":"2026-07-24T00:21:35.399119Z","end":"2026-07-24T00:21:35.555001Z","steps":["trace[960698312] 'agreement among raft nodes before linearized reading' (duration: 155.698842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:21:35.555549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.404306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T00:21:35.555666Z","caller":"traceutil/trace.go:171","msg":"trace[1564952055] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15013; }","duration":"156.576959ms","start":"2026-07-24T00:21:35.399064Z","end":"2026-07-24T00:21:35.555641Z","steps":["trace[1564952055] 'agreement among raft nodes before linearized reading' (duration: 155.685252ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:22:17.255835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12819} {"level":"info","ts":"2026-07-24T00:22:17.333615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12819,"took":"74.071134ms","hash":3992348765,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":30629888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T00:22:17.333687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3992348765,"revision":12819,"compact-revision":10739} {"level":"warn","ts":"2026-07-24T00:27:17.165829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.468902ms","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-24T00:27:17.165953Z","caller":"traceutil/trace.go:171","msg":"trace[1597508719] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17560; }","duration":"129.681426ms","start":"2026-07-24T00:27:17.036247Z","end":"2026-07-24T00:27:17.165928Z","steps":["trace[1597508719] 'range keys from in-memory index tree' (duration: 129.186347ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:27:17.310727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15285} {"level":"warn","ts":"2026-07-24T00:27:17.61362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.458504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:27:17.613715Z","caller":"traceutil/trace.go:171","msg":"trace[1736954825] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17561; }","duration":"164.663107ms","start":"2026-07-24T00:27:17.449029Z","end":"2026-07-24T00:27:17.613692Z","steps":["trace[1736954825] 'range keys from in-memory index tree' (duration: 164.134587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:17.613704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.418803ms","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-24T00:27:17.61382Z","caller":"traceutil/trace.go:171","msg":"trace[10565281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17561; }","duration":"164.585816ms","start":"2026-07-24T00:27:17.449205Z","end":"2026-07-24T00:27:17.613791Z","steps":["trace[10565281] 'range keys from in-memory index tree' (duration: 164.206699ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:27:17.614078Z","caller":"traceutil/trace.go:171","msg":"trace[341198397] compact","detail":"{revision:15285; response_revision:17561; }","duration":"332.528995ms","start":"2026-07-24T00:27:17.281514Z","end":"2026-07-24T00:27:17.614043Z","steps":[],"step_count":0} {"level":"warn","ts":"2026-07-24T00:27:17.614185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.281461Z","time spent":"332.718839ms","remote":"127.0.0.1:59904","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-24T00:27:17.614233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.012941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:27:17.614266Z","caller":"traceutil/trace.go:171","msg":"trace[1229748854] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:17561; }","duration":"109.050941ms","start":"2026-07-24T00:27:17.505204Z","end":"2026-07-24T00:27:17.614255Z","steps":["trace[1229748854] 'count revisions from in-memory index tree' (duration: 108.923589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:17.614332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.986619ms","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-24T00:27:17.614378Z","caller":"traceutil/trace.go:171","msg":"trace[1749551872] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:17561; }","duration":"230.06258ms","start":"2026-07-24T00:27:17.384298Z","end":"2026-07-24T00:27:17.61436Z","steps":["trace[1749551872] 'count revisions from in-memory index tree' (duration: 229.921078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:17.614413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.948043ms","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-24T00:27:17.614501Z","caller":"traceutil/trace.go:171","msg":"trace[1228106991] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17561; }","duration":"286.105456ms","start":"2026-07-24T00:27:17.328374Z","end":"2026-07-24T00:27:17.614479Z","steps":["trace[1228106991] 'range keys from in-memory index tree' (duration: 285.838041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:17.614563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.738129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dn8v5\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-07-24T00:27:17.614593Z","caller":"traceutil/trace.go:171","msg":"trace[276823770] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dn8v5; range_end:; response_count:1; response_revision:17561; }","duration":"332.78595ms","start":"2026-07-24T00:27:17.281798Z","end":"2026-07-24T00:27:17.614584Z","steps":["trace[276823770] 'agreement among raft nodes before linearized reading' (duration: 34.575253ms)","trace[276823770] 'range keys from in-memory index tree' (duration: 298.092895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:27:17.614615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.281789Z","time spent":"332.81978ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20782,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dn8v5\" "} {"level":"warn","ts":"2026-07-24T00:27:17.614641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.786247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:27:17.61467Z","caller":"traceutil/trace.go:171","msg":"trace[483148008] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:17561; }","duration":"311.843128ms","start":"2026-07-24T00:27:17.302818Z","end":"2026-07-24T00:27:17.614661Z","steps":["trace[483148008] 'count revisions from in-memory index tree' (duration: 298.181086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:17.614605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.319997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-24T00:27:17.614691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.302807Z","time spent":"311.876619ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:27:17.614723Z","caller":"traceutil/trace.go:171","msg":"trace[585117659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17561; }","duration":"168.49005ms","start":"2026-07-24T00:27:17.446206Z","end":"2026-07-24T00:27:17.614697Z","steps":["trace[585117659] 'range keys from in-memory index tree' (duration: 168.102173ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:27:17.900899Z","caller":"traceutil/trace.go:171","msg":"trace[1619833687] transaction","detail":"{read_only:false; response_revision:17563; number_of_response:1; }","duration":"274.515333ms","start":"2026-07-24T00:27:17.626348Z","end":"2026-07-24T00:27:17.900864Z","steps":["trace[1619833687] 'process raft request' (duration: 274.366389ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:27:17.900915Z","caller":"traceutil/trace.go:171","msg":"trace[871101810] transaction","detail":"{read_only:false; response_revision:17562; number_of_response:1; }","duration":"274.542443ms","start":"2026-07-24T00:27:17.626344Z","end":"2026-07-24T00:27:17.900886Z","steps":["trace[871101810] 'process raft request' (duration: 237.597734ms)","trace[871101810] 'compare' (duration: 36.450939ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:27:18.001457Z","caller":"traceutil/trace.go:171","msg":"trace[1569481433] transaction","detail":"{read_only:false; response_revision:17564; number_of_response:1; }","duration":"374.917108ms","start":"2026-07-24T00:27:17.626491Z","end":"2026-07-24T00:27:18.001408Z","steps":["trace[1569481433] 'process raft request' (duration: 358.34618ms)","trace[1569481433] 'compare' (duration: 16.318983ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:27:18.001684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.626484Z","time spent":"375.083251ms","remote":"127.0.0.1:60132","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-24T00:27:18.00291Z","caller":"traceutil/trace.go:171","msg":"trace[112424868] transaction","detail":"{read_only:false; response_revision:17565; number_of_response:1; }","duration":"376.261984ms","start":"2026-07-24T00:27:17.62663Z","end":"2026-07-24T00:27:18.002892Z","steps":["trace[112424868] 'process raft request' (duration: 374.712833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:18.003033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.626604Z","time spent":"376.384495ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:27:18.003274Z","caller":"traceutil/trace.go:171","msg":"trace[66574003] transaction","detail":"{read_only:false; response_revision:17566; number_of_response:1; }","duration":"372.774057ms","start":"2026-07-24T00:27:17.630422Z","end":"2026-07-24T00:27:18.003196Z","steps":["trace[66574003] 'process raft request' (duration: 372.078814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:18.003433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.343396ms","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-24T00:27:18.003489Z","caller":"traceutil/trace.go:171","msg":"trace[4946066] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17566; }","duration":"375.441849ms","start":"2026-07-24T00:27:17.628036Z","end":"2026-07-24T00:27:18.003477Z","steps":["trace[4946066] 'agreement among raft nodes before linearized reading' (duration: 375.186884ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:27:18.003158Z","caller":"traceutil/trace.go:171","msg":"trace[1489213838] linearizableReadLoop","detail":"{readStateIndex:18171; appliedIndex:18167; }","duration":"375.063641ms","start":"2026-07-24T00:27:17.628053Z","end":"2026-07-24T00:27:18.003117Z","steps":["trace[1489213838] 'read index received' (duration: 235.892653ms)","trace[1489213838] 'applied index is now lower than readState.Index' (duration: 139.167658ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:27:18.003531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.628027Z","time spent":"375.49463ms","remote":"127.0.0.1:60064","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-24T00:27:18.003713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.630378Z","time spent":"373.056112ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:27:18.004054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.931954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:27:18.00411Z","caller":"traceutil/trace.go:171","msg":"trace[681616084] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:17566; }","duration":"307.035766ms","start":"2026-07-24T00:27:17.697061Z","end":"2026-07-24T00:27:18.004097Z","steps":["trace[681616084] 'agreement among raft nodes before linearized reading' (duration: 306.950415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:18.004145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.697008Z","time spent":"307.128368ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:27:18.00469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.170683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946775"} {"level":"info","ts":"2026-07-24T00:27:18.004752Z","caller":"traceutil/trace.go:171","msg":"trace[1644400337] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17566; }","duration":"376.248284ms","start":"2026-07-24T00:27:17.628492Z","end":"2026-07-24T00:27:18.00474Z","steps":["trace[1644400337] 'agreement among raft nodes before linearized reading' (duration: 375.121642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:27:18.004791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:27:17.628486Z","time spent":"376.293495ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":946798,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-24T00:27:18.073852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15285,"took":"759.811277ms","hash":1245923917,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T00:27:18.073926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245923917,"revision":15285,"compact-revision":12819} {"level":"info","ts":"2026-07-24T00:30:49.333556Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:30:49.341447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T00:30:49.341549Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T00:32:17.625701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17561} {"level":"info","ts":"2026-07-24T00:32:17.731071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17561,"took":"100.815111ms","hash":3910499286,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":34131968,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T00:32:17.731186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910499286,"revision":17561,"compact-revision":15285} {"level":"info","ts":"2026-07-24T00:33:00.283406Z","caller":"traceutil/trace.go:171","msg":"trace[1657508290] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"277.097144ms","start":"2026-07-24T00:33:00.006274Z","end":"2026-07-24T00:33:00.283371Z","steps":["trace[1657508290] 'process raft request' (duration: 276.86309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.659721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.660137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:33:00.660159Z","caller":"traceutil/trace.go:171","msg":"trace[669400495] transaction","detail":"{read_only:false; response_revision:20218; number_of_response:1; }","duration":"567.246812ms","start":"2026-07-24T00:33:00.092882Z","end":"2026-07-24T00:33:00.660129Z","steps":["trace[669400495] 'process raft request' (duration: 566.961246ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:33:00.660312Z","caller":"traceutil/trace.go:171","msg":"trace[43977232] transaction","detail":"{read_only:false; response_revision:20219; number_of_response:1; }","duration":"507.913214ms","start":"2026-07-24T00:33:00.152371Z","end":"2026-07-24T00:33:00.660284Z","steps":["trace[43977232] 'process raft request' (duration: 507.588178ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:33:00.660126Z","caller":"traceutil/trace.go:171","msg":"trace[1637468696] transaction","detail":"{read_only:false; response_revision:20217; number_of_response:1; }","duration":"651.761339ms","start":"2026-07-24T00:33:00.008275Z","end":"2026-07-24T00:33:00.660036Z","steps":["trace[1637468696] 'process raft request' (duration: 409.679974ms)","trace[1637468696] 'compare' (duration: 241.363791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:33:00.660431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.152345Z","time spent":"508.012157ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:33:00.660424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.092853Z","time spent":"567.401104ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:33:00.660427Z","caller":"traceutil/trace.go:171","msg":"trace[9250326] linearizableReadLoop","detail":"{readStateIndex:20909; appliedIndex:20907; }","duration":"648.227331ms","start":"2026-07-24T00:33:00.012154Z","end":"2026-07-24T00:33:00.660382Z","steps":["trace[9250326] 'read index received' (duration: 271.022869ms)","trace[9250326] 'applied index is now lower than readState.Index' (duration: 377.200462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:33:00.66059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.008237Z","time spent":"652.201787ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:33:00.660647Z","caller":"traceutil/trace.go:171","msg":"trace[904961053] transaction","detail":"{read_only:false; response_revision:20220; number_of_response:1; }","duration":"132.956905ms","start":"2026-07-24T00:33:00.527606Z","end":"2026-07-24T00:33:00.660563Z","steps":["trace[904961053] 'process raft request' (duration: 132.601678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.660875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.71776ms","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-24T00:33:00.660943Z","caller":"traceutil/trace.go:171","msg":"trace[1239952992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20220; }","duration":"648.825973ms","start":"2026-07-24T00:33:00.012103Z","end":"2026-07-24T00:33:00.660929Z","steps":["trace[1239952992] 'agreement among raft nodes before linearized reading' (duration: 648.357874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.660985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.012088Z","time spent":"648.887313ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T00:33:00.972643Z","caller":"traceutil/trace.go:171","msg":"trace[1124675260] linearizableReadLoop","detail":"{readStateIndex:20913; appliedIndex:20912; }","duration":"312.065106ms","start":"2026-07-24T00:33:00.660531Z","end":"2026-07-24T00:33:00.972596Z","steps":["trace[1124675260] 'read index received' (duration: 245.052353ms)","trace[1124675260] 'applied index is now lower than readState.Index' (duration: 67.010923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:33:00.972942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.097886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:33:00.973136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.769892ms","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-24T00:33:00.973184Z","caller":"traceutil/trace.go:171","msg":"trace[1612599521] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20221; }","duration":"648.848284ms","start":"2026-07-24T00:33:00.324319Z","end":"2026-07-24T00:33:00.973168Z","steps":["trace[1612599521] 'agreement among raft nodes before linearized reading' (duration: 648.738922ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:33:00.973168Z","caller":"traceutil/trace.go:171","msg":"trace[1611350899] transaction","detail":"{read_only:false; response_revision:20221; number_of_response:1; }","duration":"432.616233ms","start":"2026-07-24T00:33:00.540489Z","end":"2026-07-24T00:33:00.973105Z","steps":["trace[1611350899] 'process raft request' (duration: 365.098931ms)","trace[1611350899] 'compare' (duration: 66.324039ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:33:00.973232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.324296Z","time spent":"648.926656ms","remote":"127.0.0.1:60064","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":"info","ts":"2026-07-24T00:33:00.973137Z","caller":"traceutil/trace.go:171","msg":"trace[1659774386] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:20221; }","duration":"417.353541ms","start":"2026-07-24T00:33:00.555743Z","end":"2026-07-24T00:33:00.973097Z","steps":["trace[1659774386] 'agreement among raft nodes before linearized reading' (duration: 416.962784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.973318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.555729Z","time spent":"417.566245ms","remote":"127.0.0.1:34386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:33:00.973318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.600548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:33:00.973383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.540451Z","time spent":"432.800057ms","remote":"127.0.0.1:60132","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-24T00:33:00.973418Z","caller":"traceutil/trace.go:171","msg":"trace[2122035169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20221; }","duration":"848.753721ms","start":"2026-07-24T00:33:00.124637Z","end":"2026-07-24T00:33:00.97339Z","steps":["trace[2122035169] 'agreement among raft nodes before linearized reading' (duration: 848.566377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.973002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.023322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-07-24T00:33:00.973468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.124613Z","time spent":"848.844622ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T00:33:00.973564Z","caller":"traceutil/trace.go:171","msg":"trace[1105893767] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:20221; }","duration":"550.595083ms","start":"2026-07-24T00:33:00.422893Z","end":"2026-07-24T00:33:00.973488Z","steps":["trace[1105893767] 'agreement among raft nodes before linearized reading' (duration: 549.90329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.973642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.496162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T00:33:00.973721Z","caller":"traceutil/trace.go:171","msg":"trace[1115619403] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20221; }","duration":"864.608984ms","start":"2026-07-24T00:33:00.109086Z","end":"2026-07-24T00:33:00.973695Z","steps":["trace[1115619403] 'agreement among raft nodes before linearized reading' (duration: 864.219607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.97371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.422864Z","time spent":"550.820246ms","remote":"127.0.0.1:59924","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.77\" "} {"level":"warn","ts":"2026-07-24T00:33:00.973811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.108954Z","time spent":"864.810889ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T00:33:00.974059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.698346ms","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-24T00:33:00.974219Z","caller":"traceutil/trace.go:171","msg":"trace[1597331443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20221; }","duration":"395.968782ms","start":"2026-07-24T00:33:00.578213Z","end":"2026-07-24T00:33:00.974182Z","steps":["trace[1597331443] 'agreement among raft nodes before linearized reading' (duration: 395.133516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.974303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.57818Z","time spent":"396.108564ms","remote":"127.0.0.1:60132","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":"info","ts":"2026-07-24T00:33:00.975911Z","caller":"traceutil/trace.go:171","msg":"trace[1757152491] transaction","detail":"{read_only:false; response_revision:20222; number_of_response:1; }","duration":"307.484527ms","start":"2026-07-24T00:33:00.668371Z","end":"2026-07-24T00:33:00.975856Z","steps":["trace[1757152491] 'process raft request' (duration: 306.823154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:33:00.976072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:33:00.668339Z","time spent":"307.64239ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:33:01.147667Z","caller":"traceutil/trace.go:171","msg":"trace[1493898901] transaction","detail":"{read_only:false; response_revision:20226; number_of_response:1; }","duration":"160.272218ms","start":"2026-07-24T00:33:00.98735Z","end":"2026-07-24T00:33:01.147622Z","steps":["trace[1493898901] 'process raft request' (duration: 160.128516ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:33:01.147721Z","caller":"traceutil/trace.go:171","msg":"trace[203272261] transaction","detail":"{read_only:false; response_revision:20225; number_of_response:1; }","duration":"162.653734ms","start":"2026-07-24T00:33:00.985007Z","end":"2026-07-24T00:33:01.147661Z","steps":["trace[203272261] 'process raft request' (duration: 155.12544ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:06.697844Z","caller":"traceutil/trace.go:171","msg":"trace[389390548] transaction","detail":"{read_only:false; response_revision:22715; number_of_response:1; }","duration":"107.863691ms","start":"2026-07-24T00:37:06.589952Z","end":"2026-07-24T00:37:06.697816Z","steps":["trace[389390548] 'process raft request' (duration: 107.119547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:07.006671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.948926ms","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-24T00:37:07.006776Z","caller":"traceutil/trace.go:171","msg":"trace[1554535422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22716; }","duration":"166.100238ms","start":"2026-07-24T00:37:06.840654Z","end":"2026-07-24T00:37:07.006754Z","steps":["trace[1554535422] 'range keys from in-memory index tree' (duration: 165.748842ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:17.633244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20000} {"level":"info","ts":"2026-07-24T00:37:17.745722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20000,"took":"107.365872ms","hash":1095423183,"current-db-size-bytes":57094144,"current-db-size":"57 MB","current-db-size-in-use-bytes":41611264,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-24T00:37:17.745807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1095423183,"revision":20000,"compact-revision":17561} {"level":"info","ts":"2026-07-24T00:40:35.007742Z","caller":"traceutil/trace.go:171","msg":"trace[1948830968] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"191.554967ms","start":"2026-07-24T00:40:34.816157Z","end":"2026-07-24T00:40:35.007712Z","steps":["trace[1948830968] 'process raft request' (duration: 191.16544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.430252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.798455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:40:35.430444Z","caller":"traceutil/trace.go:171","msg":"trace[1299980960] linearizableReadLoop","detail":"{readStateIndex:25667; appliedIndex:25664; }","duration":"511.946238ms","start":"2026-07-24T00:40:34.918485Z","end":"2026-07-24T00:40:35.430431Z","steps":["trace[1299980960] 'read index received' (duration: 88.906217ms)","trace[1299980960] 'applied index is now lower than readState.Index' (duration: 423.039121ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:35.430531Z","caller":"traceutil/trace.go:171","msg":"trace[727478] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"593.458462ms","start":"2026-07-24T00:40:34.837064Z","end":"2026-07-24T00:40:35.430522Z","steps":["trace[727478] 'process raft request' (duration: 280.308941ms)","trace[727478] 'compare' (duration: 312.675112ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:35.43058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:34.837002Z","time spent":"593.553894ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:35.430542Z","caller":"traceutil/trace.go:171","msg":"trace[1129450550] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"393.203378ms","start":"2026-07-24T00:40:35.037303Z","end":"2026-07-24T00:40:35.430507Z","steps":["trace[1129450550] 'process raft request' (duration: 393.101276ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:35.430679Z","caller":"traceutil/trace.go:171","msg":"trace[377324465] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"565.005286ms","start":"2026-07-24T00:40:34.865645Z","end":"2026-07-24T00:40:35.43065Z","steps":["trace[377324465] 'process raft request' (duration: 564.70439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.43074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:35.037277Z","time spent":"393.358121ms","remote":"127.0.0.1:60132","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-24T00:40:35.430782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.07453ms","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-24T00:40:35.430806Z","caller":"traceutil/trace.go:171","msg":"trace[1946433864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24845; }","duration":"201.137702ms","start":"2026-07-24T00:40:35.229661Z","end":"2026-07-24T00:40:35.430799Z","steps":["trace[1946433864] 'agreement among raft nodes before linearized reading' (duration: 201.023149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.430771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.538555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:40:35.430814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:34.865632Z","time spent":"565.116549ms","remote":"127.0.0.1:60132","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-24T00:40:35.430836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.341785ms","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-24T00:40:35.430867Z","caller":"traceutil/trace.go:171","msg":"trace[1024995459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24845; }","duration":"305.697188ms","start":"2026-07-24T00:40:35.125146Z","end":"2026-07-24T00:40:35.430843Z","steps":["trace[1024995459] 'agreement among raft nodes before linearized reading' (duration: 305.525205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.430955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:35.125124Z","time spent":"305.82211ms","remote":"127.0.0.1:59880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T00:40:35.430878Z","caller":"traceutil/trace.go:171","msg":"trace[549092281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24845; }","duration":"512.436067ms","start":"2026-07-24T00:40:34.918431Z","end":"2026-07-24T00:40:35.430867Z","steps":["trace[549092281] 'agreement among raft nodes before linearized reading' (duration: 512.188922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.43083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.27362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:35.43128Z","caller":"traceutil/trace.go:171","msg":"trace[851942547] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24845; }","duration":"218.775309ms","start":"2026-07-24T00:40:35.212488Z","end":"2026-07-24T00:40:35.431264Z","steps":["trace[851942547] 'agreement among raft nodes before linearized reading' (duration: 218.233789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:35.43142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:34.918405Z","time spent":"512.937347ms","remote":"127.0.0.1:60132","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-24T00:40:39.827112Z","caller":"traceutil/trace.go:171","msg":"trace[1813940053] linearizableReadLoop","detail":"{readStateIndex:25701; appliedIndex:25699; }","duration":"261.635742ms","start":"2026-07-24T00:40:39.565453Z","end":"2026-07-24T00:40:39.827089Z","steps":["trace[1813940053] 'read index received' (duration: 569.61µs)","trace[1813940053] 'applied index is now lower than readState.Index' (duration: 261.064781ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:39.827229Z","caller":"traceutil/trace.go:171","msg":"trace[1909210300] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"297.130774ms","start":"2026-07-24T00:40:39.530084Z","end":"2026-07-24T00:40:39.827215Z","steps":["trace[1909210300] 'process raft request' (duration: 296.798968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.827492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.00897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:40:39.827606Z","caller":"traceutil/trace.go:171","msg":"trace[1758482631] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24877; }","duration":"262.193514ms","start":"2026-07-24T00:40:39.565387Z","end":"2026-07-24T00:40:39.82758Z","steps":["trace[1758482631] 'agreement among raft nodes before linearized reading' (duration: 261.982419ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:39.932327Z","caller":"traceutil/trace.go:171","msg":"trace[1773941236] linearizableReadLoop","detail":"{readStateIndex:25702; appliedIndex:25701; }","duration":"105.114619ms","start":"2026-07-24T00:40:39.827183Z","end":"2026-07-24T00:40:39.932297Z","steps":["trace[1773941236] 'read index received' (duration: 103.766142ms)","trace[1773941236] 'applied index is now lower than readState.Index' (duration: 1.347067ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:39.932622Z","caller":"traceutil/trace.go:171","msg":"trace[1927839952] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"141.170839ms","start":"2026-07-24T00:40:39.791439Z","end":"2026-07-24T00:40:39.93261Z","steps":["trace[1927839952] 'process raft request' (duration: 139.563238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.933114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.852091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:39.933254Z","caller":"traceutil/trace.go:171","msg":"trace[606203101] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24878; }","duration":"343.038455ms","start":"2026-07-24T00:40:39.590166Z","end":"2026-07-24T00:40:39.933205Z","steps":["trace[606203101] 'agreement among raft nodes before linearized reading' (duration: 342.77358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.933303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.009018ms","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-24T00:40:39.93332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:39.590155Z","time spent":"343.152937ms","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:40:39.933353Z","caller":"traceutil/trace.go:171","msg":"trace[2083159206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24878; }","duration":"102.100039ms","start":"2026-07-24T00:40:39.831241Z","end":"2026-07-24T00:40:39.933341Z","steps":["trace[2083159206] 'agreement among raft nodes before linearized reading' (duration: 101.901265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.933332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.525678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:40:39.933436Z","caller":"traceutil/trace.go:171","msg":"trace[442124121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24878; }","duration":"113.717153ms","start":"2026-07-24T00:40:39.819694Z","end":"2026-07-24T00:40:39.933411Z","steps":["trace[442124121] 'agreement among raft nodes before linearized reading' (duration: 113.417467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.933472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.02231ms","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-24T00:40:39.933593Z","caller":"traceutil/trace.go:171","msg":"trace[1880818443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24878; }","duration":"299.255203ms","start":"2026-07-24T00:40:39.634309Z","end":"2026-07-24T00:40:39.933565Z","steps":["trace[1880818443] 'agreement among raft nodes before linearized reading' (duration: 298.826745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:39.934135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.770106ms","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-24T00:40:39.934477Z","caller":"traceutil/trace.go:171","msg":"trace[75564720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24878; }","duration":"186.997ms","start":"2026-07-24T00:40:39.747308Z","end":"2026-07-24T00:40:39.934305Z","steps":["trace[75564720] 'agreement among raft nodes before linearized reading' (duration: 185.713305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:40.89657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.975922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:40:40.896919Z","caller":"traceutil/trace.go:171","msg":"trace[1650414563] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"244.689157ms","start":"2026-07-24T00:40:40.652213Z","end":"2026-07-24T00:40:40.896902Z","steps":["trace[1650414563] 'process raft request' (duration: 244.631336ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:40.897142Z","caller":"traceutil/trace.go:171","msg":"trace[416524522] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"361.04011ms","start":"2026-07-24T00:40:40.536097Z","end":"2026-07-24T00:40:40.897137Z","steps":["trace[416524522] 'process raft request' (duration: 360.718254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:40.897191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:40.536061Z","time spent":"361.097632ms","remote":"127.0.0.1:60132","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-24T00:40:40.897303Z","caller":"traceutil/trace.go:171","msg":"trace[2048233082] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25706; }","duration":"257.325479ms","start":"2026-07-24T00:40:40.639957Z","end":"2026-07-24T00:40:40.897283Z","steps":["trace[2048233082] 'read index received' (duration: 10.827537ms)","trace[2048233082] 'applied index is now lower than readState.Index' (duration: 246.494992ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:40.89726Z","caller":"traceutil/trace.go:171","msg":"trace[1857811014] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"361.810495ms","start":"2026-07-24T00:40:40.53537Z","end":"2026-07-24T00:40:40.89718Z","steps":["trace[1857811014] 'process raft request' (duration: 361.417728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:40.897378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.426881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:40.897407Z","caller":"traceutil/trace.go:171","msg":"trace[411466494] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24887; }","duration":"257.506792ms","start":"2026-07-24T00:40:40.639891Z","end":"2026-07-24T00:40:40.897398Z","steps":["trace[411466494] 'agreement among raft nodes before linearized reading' (duration: 257.428311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:40.897589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:40.535338Z","time spent":"362.08455ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:40.898098Z","caller":"traceutil/trace.go:171","msg":"trace[1379451128] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"408.529333ms","start":"2026-07-24T00:40:40.489539Z","end":"2026-07-24T00:40:40.898068Z","steps":["trace[1379451128] 'process raft request' (duration: 407.169986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:40.898147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:40.489514Z","time spent":"408.608154ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:40.898186Z","caller":"traceutil/trace.go:171","msg":"trace[593681226] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"412.179992ms","start":"2026-07-24T00:40:40.485974Z","end":"2026-07-24T00:40:40.898154Z","steps":["trace[593681226] 'process raft request' (duration: 276.525538ms)","trace[593681226] 'compare' (duration: 133.659516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:40.898317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:40.485942Z","time spent":"412.308184ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:41.081238Z","caller":"traceutil/trace.go:171","msg":"trace[59186334] linearizableReadLoop","detail":"{readStateIndex:25713; appliedIndex:25712; }","duration":"180.836121ms","start":"2026-07-24T00:40:40.90037Z","end":"2026-07-24T00:40:41.081206Z","steps":["trace[59186334] 'read index received' (duration: 126.004098ms)","trace[59186334] 'applied index is now lower than readState.Index' (duration: 54.831073ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:41.081696Z","caller":"traceutil/trace.go:171","msg":"trace[1501647759] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"262.527439ms","start":"2026-07-24T00:40:40.819148Z","end":"2026-07-24T00:40:41.081676Z","steps":["trace[1501647759] 'process raft request' (duration: 207.268959ms)","trace[1501647759] 'compare' (duration: 54.577667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:41.081818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.21857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-07-24T00:40:41.081827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.451112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-24T00:40:41.081874Z","caller":"traceutil/trace.go:171","msg":"trace[545327429] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24888; }","duration":"153.337742ms","start":"2026-07-24T00:40:40.92852Z","end":"2026-07-24T00:40:41.081857Z","steps":["trace[545327429] 'agreement among raft nodes before linearized reading' (duration: 153.125468ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:41.081892Z","caller":"traceutil/trace.go:171","msg":"trace[950682420] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24888; }","duration":"181.551996ms","start":"2026-07-24T00:40:40.900319Z","end":"2026-07-24T00:40:41.081871Z","steps":["trace[950682420] 'agreement among raft nodes before linearized reading' (duration: 181.349302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:41.829132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.491698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:40:41.829482Z","caller":"traceutil/trace.go:171","msg":"trace[257530795] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"255.127477ms","start":"2026-07-24T00:40:41.574312Z","end":"2026-07-24T00:40:41.829439Z","steps":["trace[257530795] 'process raft request' (duration: 116.197551ms)","trace[257530795] 'compare' (duration: 138.345225ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:42.144847Z","caller":"traceutil/trace.go:171","msg":"trace[1845931769] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25717; }","duration":"194.139548ms","start":"2026-07-24T00:40:41.950666Z","end":"2026-07-24T00:40:42.144806Z","steps":["trace[1845931769] 'read index received' (duration: 193.74288ms)","trace[1845931769] 'applied index is now lower than readState.Index' (duration: 395.138µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:42.14498Z","caller":"traceutil/trace.go:171","msg":"trace[312022588] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"207.406721ms","start":"2026-07-24T00:40:41.937533Z","end":"2026-07-24T00:40:42.14494Z","steps":["trace[312022588] 'process raft request' (duration: 207.041404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.145172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.471014ms","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-24T00:40:42.145217Z","caller":"traceutil/trace.go:171","msg":"trace[691820945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24893; }","duration":"194.570456ms","start":"2026-07-24T00:40:41.950632Z","end":"2026-07-24T00:40:42.145203Z","steps":["trace[691820945] 'agreement among raft nodes before linearized reading' (duration: 194.348572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.145384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.623359ms","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-24T00:40:42.145418Z","caller":"traceutil/trace.go:171","msg":"trace[928566631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24893; }","duration":"140.711391ms","start":"2026-07-24T00:40:42.004697Z","end":"2026-07-24T00:40:42.145408Z","steps":["trace[928566631] 'agreement among raft nodes before linearized reading' (duration: 140.523317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.145481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.343955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:40:42.145521Z","caller":"traceutil/trace.go:171","msg":"trace[374372260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24893; }","duration":"139.392606ms","start":"2026-07-24T00:40:42.006114Z","end":"2026-07-24T00:40:42.145506Z","steps":["trace[374372260] 'agreement among raft nodes before linearized reading' (duration: 139.306054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.14553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.390215ms","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-24T00:40:42.145558Z","caller":"traceutil/trace.go:171","msg":"trace[1661220901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24893; }","duration":"140.412105ms","start":"2026-07-24T00:40:42.005135Z","end":"2026-07-24T00:40:42.145547Z","steps":["trace[1661220901] 'agreement among raft nodes before linearized reading' (duration: 140.343514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.428349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.563697ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:42.428495Z","caller":"traceutil/trace.go:171","msg":"trace[1577069132] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24893; }","duration":"129.73075ms","start":"2026-07-24T00:40:42.298741Z","end":"2026-07-24T00:40:42.428472Z","steps":["trace[1577069132] 'range keys from in-memory index tree' (duration: 129.543136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.42868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.255201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:40:42.428937Z","caller":"traceutil/trace.go:171","msg":"trace[1509582624] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"279.273301ms","start":"2026-07-24T00:40:42.149639Z","end":"2026-07-24T00:40:42.428912Z","steps":["trace[1509582624] 'process raft request' (duration: 136.670864ms)","trace[1509582624] 'compare' (duration: 142.103718ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:42.429233Z","caller":"traceutil/trace.go:171","msg":"trace[2025907489] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"277.673301ms","start":"2026-07-24T00:40:42.151545Z","end":"2026-07-24T00:40:42.429219Z","steps":["trace[2025907489] 'process raft request' (duration: 277.301574ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:42.429427Z","caller":"traceutil/trace.go:171","msg":"trace[1462128136] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25718; }","duration":"150.045569ms","start":"2026-07-24T00:40:42.279348Z","end":"2026-07-24T00:40:42.429394Z","steps":["trace[1462128136] 'read index received' (duration: 7.077805ms)","trace[1462128136] 'applied index is now lower than readState.Index' (duration: 142.966274ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:42.429508Z","caller":"traceutil/trace.go:171","msg":"trace[698804725] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"276.559768ms","start":"2026-07-24T00:40:42.152928Z","end":"2026-07-24T00:40:42.429488Z","steps":["trace[698804725] 'process raft request' (duration: 276.410005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:42.429551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.191583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:42.429585Z","caller":"traceutil/trace.go:171","msg":"trace[123898444] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24897; }","duration":"150.267013ms","start":"2026-07-24T00:40:42.279305Z","end":"2026-07-24T00:40:42.429572Z","steps":["trace[123898444] 'agreement among raft nodes before linearized reading' (duration: 150.198312ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:42.429914Z","caller":"traceutil/trace.go:171","msg":"trace[109424635] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"277.811633ms","start":"2026-07-24T00:40:42.151626Z","end":"2026-07-24T00:40:42.429437Z","steps":["trace[109424635] 'process raft request' (duration: 277.532748ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:42.721832Z","caller":"traceutil/trace.go:171","msg":"trace[1388812797] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"121.983292ms","start":"2026-07-24T00:40:42.599802Z","end":"2026-07-24T00:40:42.721785Z","steps":["trace[1388812797] 'process raft request' (duration: 79.373263ms)","trace[1388812797] 'compare' (duration: 42.16944ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:43.046622Z","caller":"traceutil/trace.go:171","msg":"trace[341370482] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"142.656899ms","start":"2026-07-24T00:40:42.903938Z","end":"2026-07-24T00:40:43.046595Z","steps":["trace[341370482] 'process raft request' (duration: 142.477285ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.071807Z","caller":"traceutil/trace.go:171","msg":"trace[1453688703] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"167.71119ms","start":"2026-07-24T00:40:42.904059Z","end":"2026-07-24T00:40:43.07177Z","steps":["trace[1453688703] 'process raft request' (duration: 167.377024ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.072099Z","caller":"traceutil/trace.go:171","msg":"trace[1290685696] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"165.070079ms","start":"2026-07-24T00:40:42.907011Z","end":"2026-07-24T00:40:43.072081Z","steps":["trace[1290685696] 'process raft request' (duration: 164.904636ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.072109Z","caller":"traceutil/trace.go:171","msg":"trace[363854223] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"167.240461ms","start":"2026-07-24T00:40:42.904818Z","end":"2026-07-24T00:40:43.072059Z","steps":["trace[363854223] 'process raft request' (duration: 166.790213ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.072298Z","caller":"traceutil/trace.go:171","msg":"trace[1508307276] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"133.112425ms","start":"2026-07-24T00:40:42.938897Z","end":"2026-07-24T00:40:43.07201Z","steps":["trace[1508307276] 'process raft request' (duration: 133.065115ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.68452Z","caller":"traceutil/trace.go:171","msg":"trace[1898822859] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"131.105566ms","start":"2026-07-24T00:40:43.553371Z","end":"2026-07-24T00:40:43.684476Z","steps":["trace[1898822859] 'process raft request' (duration: 130.80138ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:43.8537Z","caller":"traceutil/trace.go:171","msg":"trace[1782569877] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"296.294098ms","start":"2026-07-24T00:40:43.557367Z","end":"2026-07-24T00:40:43.853661Z","steps":["trace[1782569877] 'process raft request' (duration: 296.002143ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:44.796311Z","caller":"traceutil/trace.go:171","msg":"trace[1825881523] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"330.621707ms","start":"2026-07-24T00:40:44.465651Z","end":"2026-07-24T00:40:44.796272Z","steps":["trace[1825881523] 'process raft request' (duration: 330.366923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:44.796512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:44.46563Z","time spent":"330.77555ms","remote":"127.0.0.1:60132","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-24T00:40:44.796692Z","caller":"traceutil/trace.go:171","msg":"trace[1189755307] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"330.745029ms","start":"2026-07-24T00:40:44.465934Z","end":"2026-07-24T00:40:44.796679Z","steps":["trace[1189755307] 'process raft request' (duration: 330.27861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:44.796783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:44.465924Z","time spent":"330.81987ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:44.796758Z","caller":"traceutil/trace.go:171","msg":"trace[363441899] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"330.697459ms","start":"2026-07-24T00:40:44.466009Z","end":"2026-07-24T00:40:44.796707Z","steps":["trace[363441899] 'process raft request' (duration: 330.497615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:44.797003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:44.466001Z","time spent":"330.875232ms","remote":"127.0.0.1:60132","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-24T00:40:45.163581Z","caller":"traceutil/trace.go:171","msg":"trace[1870709040] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"110.642654ms","start":"2026-07-24T00:40:45.052912Z","end":"2026-07-24T00:40:45.163555Z","steps":["trace[1870709040] 'process raft request' (duration: 110.44004ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.331551Z","caller":"traceutil/trace.go:171","msg":"trace[915949190] linearizableReadLoop","detail":"{readStateIndex:25741; appliedIndex:25737; }","duration":"186.946448ms","start":"2026-07-24T00:40:45.144568Z","end":"2026-07-24T00:40:45.331515Z","steps":["trace[915949190] 'read index received' (duration: 18.688258ms)","trace[915949190] 'applied index is now lower than readState.Index' (duration: 168.25683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:45.331758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.151992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:45.331812Z","caller":"traceutil/trace.go:171","msg":"trace[985121957] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24916; }","duration":"187.299164ms","start":"2026-07-24T00:40:45.144497Z","end":"2026-07-24T00:40:45.331796Z","steps":["trace[985121957] 'agreement among raft nodes before linearized reading' (duration: 187.148772ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.33219Z","caller":"traceutil/trace.go:171","msg":"trace[1714163045] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"253.255132ms","start":"2026-07-24T00:40:45.078915Z","end":"2026-07-24T00:40:45.33217Z","steps":["trace[1714163045] 'process raft request' (duration: 252.500087ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.3323Z","caller":"traceutil/trace.go:171","msg":"trace[1189297087] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"253.595969ms","start":"2026-07-24T00:40:45.078695Z","end":"2026-07-24T00:40:45.332291Z","steps":["trace[1189297087] 'process raft request' (duration: 252.511487ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.332453Z","caller":"traceutil/trace.go:171","msg":"trace[780926248] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"253.484556ms","start":"2026-07-24T00:40:45.078955Z","end":"2026-07-24T00:40:45.33244Z","steps":["trace[780926248] 'process raft request' (duration: 252.514547ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.795515Z","caller":"traceutil/trace.go:171","msg":"trace[1892025367] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"103.890745ms","start":"2026-07-24T00:40:45.691589Z","end":"2026-07-24T00:40:45.79548Z","steps":["trace[1892025367] 'process raft request' (duration: 103.693631ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:45.969914Z","caller":"traceutil/trace.go:171","msg":"trace[1743346651] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"109.41389ms","start":"2026-07-24T00:40:45.860463Z","end":"2026-07-24T00:40:45.969877Z","steps":["trace[1743346651] 'process raft request' (duration: 105.111848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.131699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.0125ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T00:40:46.131818Z","caller":"traceutil/trace.go:171","msg":"trace[1024300249] linearizableReadLoop","detail":"{readStateIndex:25744; appliedIndex:25742; }","duration":"237.821656ms","start":"2026-07-24T00:40:45.893977Z","end":"2026-07-24T00:40:46.131798Z","steps":["trace[1024300249] 'read index received' (duration: 71.570675ms)","trace[1024300249] 'applied index is now lower than readState.Index' (duration: 166.24868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:46.132505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.525428ms","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-24T00:40:46.132553Z","caller":"traceutil/trace.go:171","msg":"trace[1159943189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24918; }","duration":"238.61421ms","start":"2026-07-24T00:40:45.893925Z","end":"2026-07-24T00:40:46.132539Z","steps":["trace[1159943189] 'agreement among raft nodes before linearized reading' (duration: 238.443367ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:46.22097Z","caller":"traceutil/trace.go:171","msg":"trace[554450387] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"132.30416ms","start":"2026-07-24T00:40:46.088625Z","end":"2026-07-24T00:40:46.220929Z","steps":["trace[554450387] 'process raft request' (duration: 132.019624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.221104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.249043ms","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-24T00:40:46.221182Z","caller":"traceutil/trace.go:171","msg":"trace[1829590277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24919; }","duration":"300.345455ms","start":"2026-07-24T00:40:45.920817Z","end":"2026-07-24T00:40:46.221163Z","steps":["trace[1829590277] 'agreement among raft nodes before linearized reading' (duration: 300.05975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.221222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:45.920744Z","time spent":"300.468098ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T00:40:46.541575Z","caller":"traceutil/trace.go:171","msg":"trace[788719860] linearizableReadLoop","detail":"{readStateIndex:25747; appliedIndex:25745; }","duration":"318.821211ms","start":"2026-07-24T00:40:46.222721Z","end":"2026-07-24T00:40:46.541542Z","steps":["trace[788719860] 'read index received' (duration: 318.505855ms)","trace[788719860] 'applied index is now lower than readState.Index' (duration: 314.056µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:46.541681Z","caller":"traceutil/trace.go:171","msg":"trace[889127502] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"404.127268ms","start":"2026-07-24T00:40:46.137504Z","end":"2026-07-24T00:40:46.541631Z","steps":["trace[889127502] 'process raft request' (duration: 403.7439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.542233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.467853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:46.541753Z","caller":"traceutil/trace.go:171","msg":"trace[1170496121] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"359.50255ms","start":"2026-07-24T00:40:46.182171Z","end":"2026-07-24T00:40:46.541673Z","steps":["trace[1170496121] 'process raft request' (duration: 359.292586ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:46.542326Z","caller":"traceutil/trace.go:171","msg":"trace[146340163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24921; }","duration":"319.618916ms","start":"2026-07-24T00:40:46.222684Z","end":"2026-07-24T00:40:46.542303Z","steps":["trace[146340163] 'agreement among raft nodes before linearized reading' (duration: 319.412111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.542338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.137472Z","time spent":"404.76664ms","remote":"127.0.0.1:60132","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-24T00:40:46.542407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.222665Z","time spent":"319.710339ms","remote":"127.0.0.1:59892","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-24T00:40:46.542503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.182147Z","time spent":"360.217605ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":604,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:40:46.622704Z","caller":"traceutil/trace.go:171","msg":"trace[210176971] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"397.135464ms","start":"2026-07-24T00:40:46.225527Z","end":"2026-07-24T00:40:46.622663Z","steps":["trace[210176971] 'process raft request' (duration: 395.612996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.622838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.532498ms","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":"warn","ts":"2026-07-24T00:40:46.622893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.225498Z","time spent":"397.301407ms","remote":"127.0.0.1:60132","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-24T00:40:46.622875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.423903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:46.622925Z","caller":"traceutil/trace.go:171","msg":"trace[1867913579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24922; }","duration":"140.67827ms","start":"2026-07-24T00:40:46.482221Z","end":"2026-07-24T00:40:46.622899Z","steps":["trace[1867913579] 'agreement among raft nodes before linearized reading' (duration: 140.460066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.622908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.541573ms","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":"warn","ts":"2026-07-24T00:40:46.622967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.71791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:46.622971Z","caller":"traceutil/trace.go:171","msg":"trace[711922131] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24922; }","duration":"379.559275ms","start":"2026-07-24T00:40:46.243384Z","end":"2026-07-24T00:40:46.622943Z","steps":["trace[711922131] 'agreement among raft nodes before linearized reading' (duration: 379.369712ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:46.622986Z","caller":"traceutil/trace.go:171","msg":"trace[499307518] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24922; }","duration":"295.681196ms","start":"2026-07-24T00:40:46.327283Z","end":"2026-07-24T00:40:46.622965Z","steps":["trace[499307518] 'agreement among raft nodes before linearized reading' (duration: 295.529463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.623016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.243365Z","time spent":"379.641218ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:40:46.623004Z","caller":"traceutil/trace.go:171","msg":"trace[8721226] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24922; }","duration":"328.857732ms","start":"2026-07-24T00:40:46.294137Z","end":"2026-07-24T00:40:46.622994Z","steps":["trace[8721226] 'agreement among raft nodes before linearized reading' (duration: 328.787441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.623055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:46.294109Z","time spent":"328.934923ms","remote":"127.0.0.1:46416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:40:46.908527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.602245ms","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-24T00:40:46.908615Z","caller":"traceutil/trace.go:171","msg":"trace[1590134493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24923; }","duration":"107.756708ms","start":"2026-07-24T00:40:46.800837Z","end":"2026-07-24T00:40:46.908594Z","steps":["trace[1590134493] 'range keys from in-memory index tree' (duration: 107.33866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:46.90853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.595175ms","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-24T00:40:46.908676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.147136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:40:46.908742Z","caller":"traceutil/trace.go:171","msg":"trace[1055761783] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24923; }","duration":"107.239358ms","start":"2026-07-24T00:40:46.801481Z","end":"2026-07-24T00:40:46.90872Z","steps":["trace[1055761783] 'range keys from in-memory index tree' (duration: 107.015254ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:46.908758Z","caller":"traceutil/trace.go:171","msg":"trace[1651768457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24923; }","duration":"107.833649ms","start":"2026-07-24T00:40:46.800881Z","end":"2026-07-24T00:40:46.908715Z","steps":["trace[1651768457] 'range keys from in-memory index tree' (duration: 107.436062ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:47.123159Z","caller":"traceutil/trace.go:171","msg":"trace[1065455414] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"205.418353ms","start":"2026-07-24T00:40:46.917713Z","end":"2026-07-24T00:40:47.123131Z","steps":["trace[1065455414] 'process raft request' (duration: 204.695739ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:47.123382Z","caller":"traceutil/trace.go:171","msg":"trace[461626558] linearizableReadLoop","detail":"{readStateIndex:25752; appliedIndex:25749; }","duration":"178.866564ms","start":"2026-07-24T00:40:46.944477Z","end":"2026-07-24T00:40:47.123344Z","steps":["trace[461626558] 'read index received' (duration: 30.72707ms)","trace[461626558] 'applied index is now lower than readState.Index' (duration: 148.136834ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:47.123474Z","caller":"traceutil/trace.go:171","msg":"trace[2088293752] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"205.630066ms","start":"2026-07-24T00:40:46.917804Z","end":"2026-07-24T00:40:47.123434Z","steps":["trace[2088293752] 'process raft request' (duration: 205.2775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:47.1236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.101658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:47.123706Z","caller":"traceutil/trace.go:171","msg":"trace[790890580] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24926; }","duration":"179.23169ms","start":"2026-07-24T00:40:46.944461Z","end":"2026-07-24T00:40:47.123693Z","steps":["trace[790890580] 'agreement among raft nodes before linearized reading' (duration: 179.054807ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:50.058264Z","caller":"traceutil/trace.go:171","msg":"trace[1616362480] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"248.479571ms","start":"2026-07-24T00:40:49.809755Z","end":"2026-07-24T00:40:50.058235Z","steps":["trace[1616362480] 'process raft request' (duration: 248.283427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.402308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.411357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:40:50.40246Z","caller":"traceutil/trace.go:171","msg":"trace[1999597777] linearizableReadLoop","detail":"{readStateIndex:25772; appliedIndex:25770; }","duration":"389.769912ms","start":"2026-07-24T00:40:50.012666Z","end":"2026-07-24T00:40:50.402436Z","steps":["trace[1999597777] 'read index received' (duration: 45.428773ms)","trace[1999597777] 'applied index is now lower than readState.Index' (duration: 344.339149ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:50.402534Z","caller":"traceutil/trace.go:171","msg":"trace[1904973740] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"408.610083ms","start":"2026-07-24T00:40:49.993874Z","end":"2026-07-24T00:40:50.402484Z","steps":["trace[1904973740] 'process raft request' (duration: 258.9036ms)","trace[1904973740] 'compare' (duration: 149.083991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:40:50.40272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.050978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-24T00:40:50.402758Z","caller":"traceutil/trace.go:171","msg":"trace[702635238] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24946; }","duration":"390.12088ms","start":"2026-07-24T00:40:50.012627Z","end":"2026-07-24T00:40:50.402748Z","steps":["trace[702635238] 'agreement among raft nodes before linearized reading' (duration: 389.934056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.40275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:49.99384Z","time spent":"408.804197ms","remote":"127.0.0.1:60132","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-24T00:40:50.402804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.012614Z","time spent":"390.180861ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-24T00:40:50.40296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.974922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:40:50.403099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.941416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:50.403153Z","caller":"traceutil/trace.go:171","msg":"trace[428151877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24946; }","duration":"277.043059ms","start":"2026-07-24T00:40:50.126099Z","end":"2026-07-24T00:40:50.403142Z","steps":["trace[428151877] 'agreement among raft nodes before linearized reading' (duration: 276.891306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.403235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.978276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:50.403332Z","caller":"traceutil/trace.go:171","msg":"trace[2089757857] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24946; }","duration":"291.098238ms","start":"2026-07-24T00:40:50.112216Z","end":"2026-07-24T00:40:50.403315Z","steps":["trace[2089757857] 'agreement among raft nodes before linearized reading' (duration: 290.926205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.403385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.907409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:40:50.403078Z","caller":"traceutil/trace.go:171","msg":"trace[2004282750] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24946; }","duration":"309.180426ms","start":"2026-07-24T00:40:50.093874Z","end":"2026-07-24T00:40:50.403054Z","steps":["trace[2004282750] 'agreement among raft nodes before linearized reading' (duration: 308.88745ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:50.403422Z","caller":"traceutil/trace.go:171","msg":"trace[464150094] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24946; }","duration":"247.967851ms","start":"2026-07-24T00:40:50.155445Z","end":"2026-07-24T00:40:50.403413Z","steps":["trace[464150094] 'agreement among raft nodes before linearized reading' (duration: 247.887619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.403445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.093846Z","time spent":"309.582153ms","remote":"127.0.0.1:37440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:40:50.871631Z","caller":"traceutil/trace.go:171","msg":"trace[965701327] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25772; }","duration":"432.959662ms","start":"2026-07-24T00:40:50.438642Z","end":"2026-07-24T00:40:50.871601Z","steps":["trace[965701327] 'read index received' (duration: 432.901311ms)","trace[965701327] 'applied index is now lower than readState.Index' (duration: 56.941µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:40:50.871752Z","caller":"traceutil/trace.go:171","msg":"trace[1772689657] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"461.703993ms","start":"2026-07-24T00:40:50.410006Z","end":"2026-07-24T00:40:50.87171Z","steps":["trace[1772689657] 'process raft request' (duration: 461.427418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.871828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.158885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:40:50.871869Z","caller":"traceutil/trace.go:171","msg":"trace[1344173338] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24947; }","duration":"433.264578ms","start":"2026-07-24T00:40:50.438589Z","end":"2026-07-24T00:40:50.871854Z","steps":["trace[1344173338] 'agreement among raft nodes before linearized reading' (duration: 433.171886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.871915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.438571Z","time spent":"433.332028ms","remote":"127.0.0.1:34406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:40:50.871946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.40998Z","time spent":"461.868606ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:40:50.872612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.839922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-07-24T00:40:50.872662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.824474ms","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-24T00:40:50.872791Z","caller":"traceutil/trace.go:171","msg":"trace[2105820242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24949; }","duration":"225.985487ms","start":"2026-07-24T00:40:50.64679Z","end":"2026-07-24T00:40:50.872775Z","steps":["trace[2105820242] 'agreement among raft nodes before linearized reading' (duration: 225.718832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.87284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.247865ms","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-24T00:40:50.872722Z","caller":"traceutil/trace.go:171","msg":"trace[1608996204] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:24949; }","duration":"428.972074ms","start":"2026-07-24T00:40:50.443724Z","end":"2026-07-24T00:40:50.872696Z","steps":["trace[1608996204] 'agreement among raft nodes before linearized reading' (duration: 428.72193ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:50.872794Z","caller":"traceutil/trace.go:171","msg":"trace[348964129] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"293.329ms","start":"2026-07-24T00:40:50.57945Z","end":"2026-07-24T00:40:50.872779Z","steps":["trace[348964129] 'process raft request' (duration: 292.945853ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:50.872894Z","caller":"traceutil/trace.go:171","msg":"trace[1594394468] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"317.29951ms","start":"2026-07-24T00:40:50.555557Z","end":"2026-07-24T00:40:50.872856Z","steps":["trace[1594394468] 'process raft request' (duration: 316.705589ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:50.87294Z","caller":"traceutil/trace.go:171","msg":"trace[273959393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24949; }","duration":"211.391778ms","start":"2026-07-24T00:40:50.661523Z","end":"2026-07-24T00:40:50.872915Z","steps":["trace[273959393] 'agreement among raft nodes before linearized reading' (duration: 211.097602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.87272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.932845ms","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-24T00:40:50.87301Z","caller":"traceutil/trace.go:171","msg":"trace[640270750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24949; }","duration":"315.251341ms","start":"2026-07-24T00:40:50.557742Z","end":"2026-07-24T00:40:50.872993Z","steps":["trace[640270750] 'agreement among raft nodes before linearized reading' (duration: 314.803983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:40:50.873031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.555525Z","time spent":"317.433263ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:40:50.873104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.557729Z","time spent":"315.354733ms","remote":"127.0.0.1:60132","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":"warn","ts":"2026-07-24T00:40:50.872978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:40:50.443716Z","time spent":"429.246499ms","remote":"127.0.0.1:59924","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.77\" "} {"level":"info","ts":"2026-07-24T00:41:03.206321Z","caller":"traceutil/trace.go:171","msg":"trace[895072955] linearizableReadLoop","detail":"{readStateIndex:25929; appliedIndex:25928; }","duration":"105.784561ms","start":"2026-07-24T00:41:03.100502Z","end":"2026-07-24T00:41:03.206287Z","steps":["trace[895072955] 'read index received' (duration: 105.547777ms)","trace[895072955] 'applied index is now lower than readState.Index' (duration: 234.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:03.206446Z","caller":"traceutil/trace.go:171","msg":"trace[2043319072] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"118.116177ms","start":"2026-07-24T00:41:03.088272Z","end":"2026-07-24T00:41:03.206388Z","steps":["trace[2043319072] 'process raft request' (duration: 117.786811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.206621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.096607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a38387aa-2758-4e06-910f-c129bc99c4a0\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-24T00:41:03.20673Z","caller":"traceutil/trace.go:171","msg":"trace[1589017214] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a38387aa-2758-4e06-910f-c129bc99c4a0; range_end:; response_count:1; response_revision:25099; }","duration":"106.25797ms","start":"2026-07-24T00:41:03.100446Z","end":"2026-07-24T00:41:03.206704Z","steps":["trace[1589017214] 'agreement among raft nodes before linearized reading' (duration: 105.942774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.557498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.499113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:03.557631Z","caller":"traceutil/trace.go:171","msg":"trace[1810858115] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"331.141517ms","start":"2026-07-24T00:41:03.226467Z","end":"2026-07-24T00:41:03.557609Z","steps":["trace[1810858115] 'process raft request' (duration: 123.405899ms)","trace[1810858115] 'compare' (duration: 207.101776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:03.557703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.226436Z","time spent":"331.236318ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:03.561598Z","caller":"traceutil/trace.go:171","msg":"trace[851640618] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"333.040544ms","start":"2026-07-24T00:41:03.228539Z","end":"2026-07-24T00:41:03.56158Z","steps":["trace[851640618] 'process raft request' (duration: 332.815739ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:03.561655Z","caller":"traceutil/trace.go:171","msg":"trace[2013149199] linearizableReadLoop","detail":"{readStateIndex:25933; appliedIndex:25929; }","duration":"286.662143ms","start":"2026-07-24T00:41:03.274972Z","end":"2026-07-24T00:41:03.561634Z","steps":["trace[2013149199] 'read index received' (duration: 74.826086ms)","trace[2013149199] 'applied index is now lower than readState.Index' (duration: 211.834867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:03.561683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.228513Z","time spent":"333.125465ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:03.561803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.867766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:03.561771Z","caller":"traceutil/trace.go:171","msg":"trace[1373201232] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"331.391831ms","start":"2026-07-24T00:41:03.230318Z","end":"2026-07-24T00:41:03.56171Z","steps":["trace[1373201232] 'process raft request' (duration: 331.162327ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:03.561869Z","caller":"traceutil/trace.go:171","msg":"trace[1281350883] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25103; }","duration":"286.979519ms","start":"2026-07-24T00:41:03.274862Z","end":"2026-07-24T00:41:03.561842Z","steps":["trace[1281350883] 'agreement among raft nodes before linearized reading' (duration: 286.875617ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:03.5618Z","caller":"traceutil/trace.go:171","msg":"trace[1495798488] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"321.653745ms","start":"2026-07-24T00:41:03.240099Z","end":"2026-07-24T00:41:03.561753Z","steps":["trace[1495798488] 'process raft request' (duration: 321.438631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.562187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.240068Z","time spent":"321.890359ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:03.562325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.230293Z","time spent":"331.594326ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:03.574964Z","caller":"traceutil/trace.go:171","msg":"trace[1766837706] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"174.352238ms","start":"2026-07-24T00:41:03.400594Z","end":"2026-07-24T00:41:03.574946Z","steps":["trace[1766837706] 'process raft request' (duration: 174.166314ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:03.575211Z","caller":"traceutil/trace.go:171","msg":"trace[872415447] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"172.168705ms","start":"2026-07-24T00:41:03.403023Z","end":"2026-07-24T00:41:03.575192Z","steps":["trace[872415447] 'process raft request' (duration: 171.87138ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:03.575459Z","caller":"traceutil/trace.go:171","msg":"trace[71235466] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"169.017245ms","start":"2026-07-24T00:41:03.406426Z","end":"2026-07-24T00:41:03.575443Z","steps":["trace[71235466] 'process raft request' (duration: 168.705509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.577485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.964509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a38387aa-2758-4e06-910f-c129bc99c4a0\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-24T00:41:03.577585Z","caller":"traceutil/trace.go:171","msg":"trace[1111800267] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a38387aa-2758-4e06-910f-c129bc99c4a0; range_end:; response_count:1; response_revision:25106; }","duration":"212.116432ms","start":"2026-07-24T00:41:03.365426Z","end":"2026-07-24T00:41:03.577542Z","steps":["trace[1111800267] 'agreement among raft nodes before linearized reading' (duration: 209.97271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.974574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.363908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:03.974734Z","caller":"traceutil/trace.go:171","msg":"trace[763254848] linearizableReadLoop","detail":"{readStateIndex:25937; appliedIndex:25936; }","duration":"343.961951ms","start":"2026-07-24T00:41:03.630736Z","end":"2026-07-24T00:41:03.974697Z","steps":["trace[763254848] 'read index received' (duration: 220.222306ms)","trace[763254848] 'applied index is now lower than readState.Index' (duration: 123.738065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:03.974887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.152186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:03.974923Z","caller":"traceutil/trace.go:171","msg":"trace[595798710] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25107; }","duration":"344.186366ms","start":"2026-07-24T00:41:03.630721Z","end":"2026-07-24T00:41:03.974908Z","steps":["trace[595798710] 'agreement among raft nodes before linearized reading' (duration: 344.112485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.974974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.6307Z","time spent":"344.261037ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:41:03.975095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.531559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:03.975122Z","caller":"traceutil/trace.go:171","msg":"trace[2137623244] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"390.457834ms","start":"2026-07-24T00:41:03.584597Z","end":"2026-07-24T00:41:03.975055Z","steps":["trace[2137623244] 'process raft request' (duration: 266.481085ms)","trace[2137623244] 'compare' (duration: 123.169104ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:03.975217Z","caller":"traceutil/trace.go:171","msg":"trace[776753057] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25107; }","duration":"238.729554ms","start":"2026-07-24T00:41:03.736455Z","end":"2026-07-24T00:41:03.975184Z","steps":["trace[776753057] 'agreement among raft nodes before linearized reading' (duration: 238.528699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:03.975336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:03.584568Z","time spent":"390.643039ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:04.256514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.837232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:04.256631Z","caller":"traceutil/trace.go:171","msg":"trace[725496259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25107; }","duration":"131.037485ms","start":"2026-07-24T00:41:04.125565Z","end":"2026-07-24T00:41:04.256602Z","steps":["trace[725496259] 'range keys from in-memory index tree' (duration: 130.72454ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:04.295738Z","caller":"traceutil/trace.go:171","msg":"trace[74225422] linearizableReadLoop","detail":"{readStateIndex:25938; appliedIndex:25937; }","duration":"103.462826ms","start":"2026-07-24T00:41:04.192245Z","end":"2026-07-24T00:41:04.295708Z","steps":["trace[74225422] 'read index received' (duration: 103.238132ms)","trace[74225422] 'applied index is now lower than readState.Index' (duration: 223.184µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:04.295845Z","caller":"traceutil/trace.go:171","msg":"trace[1010407734] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"139.440177ms","start":"2026-07-24T00:41:04.156369Z","end":"2026-07-24T00:41:04.295809Z","steps":["trace[1010407734] 'process raft request' (duration: 139.138941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:04.295997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.722921ms","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":"info","ts":"2026-07-24T00:41:04.296096Z","caller":"traceutil/trace.go:171","msg":"trace[1802676562] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25108; }","duration":"103.895644ms","start":"2026-07-24T00:41:04.192177Z","end":"2026-07-24T00:41:04.296072Z","steps":["trace[1802676562] 'agreement among raft nodes before linearized reading' (duration: 103.70415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.150579Z","caller":"traceutil/trace.go:171","msg":"trace[100622410] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25939; }","duration":"179.432904ms","start":"2026-07-24T00:41:04.971122Z","end":"2026-07-24T00:41:05.150555Z","steps":["trace[100622410] 'read index received' (duration: 179.22761ms)","trace[100622410] 'applied index is now lower than readState.Index' (duration: 204.394µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:05.150723Z","caller":"traceutil/trace.go:171","msg":"trace[1786083707] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"184.487212ms","start":"2026-07-24T00:41:04.966221Z","end":"2026-07-24T00:41:05.150709Z","steps":["trace[1786083707] 'process raft request' (duration: 184.119075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.150926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.777551ms","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-24T00:41:05.150969Z","caller":"traceutil/trace.go:171","msg":"trace[401095510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25110; }","duration":"179.853493ms","start":"2026-07-24T00:41:04.971105Z","end":"2026-07-24T00:41:05.150958Z","steps":["trace[401095510] 'agreement among raft nodes before linearized reading' (duration: 179.72225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.151256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.049676ms","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-24T00:41:05.151953Z","caller":"traceutil/trace.go:171","msg":"trace[2088022231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25110; }","duration":"180.749781ms","start":"2026-07-24T00:41:04.971161Z","end":"2026-07-24T00:41:05.151911Z","steps":["trace[2088022231] 'agreement among raft nodes before linearized reading' (duration: 179.919174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.151411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.021758ms","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-24T00:41:05.152088Z","caller":"traceutil/trace.go:171","msg":"trace[1427989956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25110; }","duration":"177.751573ms","start":"2026-07-24T00:41:04.974311Z","end":"2026-07-24T00:41:05.152063Z","steps":["trace[1427989956] 'agreement among raft nodes before linearized reading' (duration: 176.831474ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.318613Z","caller":"traceutil/trace.go:171","msg":"trace[944754503] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"163.007858ms","start":"2026-07-24T00:41:05.155571Z","end":"2026-07-24T00:41:05.318579Z","steps":["trace[944754503] 'process raft request' (duration: 146.93221ms)","trace[944754503] 'compare' (duration: 15.879425ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:05.318965Z","caller":"traceutil/trace.go:171","msg":"trace[1843973874] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"162.199083ms","start":"2026-07-24T00:41:05.156737Z","end":"2026-07-24T00:41:05.318936Z","steps":["trace[1843973874] 'process raft request' (duration: 161.792425ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.319321Z","caller":"traceutil/trace.go:171","msg":"trace[111418950] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25940; }","duration":"107.721717ms","start":"2026-07-24T00:41:05.211556Z","end":"2026-07-24T00:41:05.319278Z","steps":["trace[111418950] 'read index received' (duration: 90.878094ms)","trace[111418950] 'applied index is now lower than readState.Index' (duration: 16.839793ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:05.319343Z","caller":"traceutil/trace.go:171","msg":"trace[1370471790] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"162.428288ms","start":"2026-07-24T00:41:05.156875Z","end":"2026-07-24T00:41:05.319303Z","steps":["trace[1370471790] 'process raft request' (duration: 161.953729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.319592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.010573ms","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-24T00:41:05.319647Z","caller":"traceutil/trace.go:171","msg":"trace[1600003935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25113; }","duration":"108.094234ms","start":"2026-07-24T00:41:05.211536Z","end":"2026-07-24T00:41:05.31963Z","steps":["trace[1600003935] 'agreement among raft nodes before linearized reading' (duration: 107.914601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.707603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.664523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:41:05.707714Z","caller":"traceutil/trace.go:171","msg":"trace[1124871067] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25114; }","duration":"198.839017ms","start":"2026-07-24T00:41:05.508848Z","end":"2026-07-24T00:41:05.707687Z","steps":["trace[1124871067] 'count revisions from in-memory index tree' (duration: 198.560432ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.712285Z","caller":"traceutil/trace.go:171","msg":"trace[1179903194] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25944; }","duration":"146.705347ms","start":"2026-07-24T00:41:05.565542Z","end":"2026-07-24T00:41:05.712247Z","steps":["trace[1179903194] 'read index received' (duration: 146.523733ms)","trace[1179903194] 'applied index is now lower than readState.Index' (duration: 179.914µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:05.712404Z","caller":"traceutil/trace.go:171","msg":"trace[1681541777] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"148.841228ms","start":"2026-07-24T00:41:05.563521Z","end":"2026-07-24T00:41:05.712362Z","steps":["trace[1681541777] 'process raft request' (duration: 148.512932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.712758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.127695ms","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-24T00:41:05.712903Z","caller":"traceutil/trace.go:171","msg":"trace[1261393635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25115; }","duration":"147.362629ms","start":"2026-07-24T00:41:05.565506Z","end":"2026-07-24T00:41:05.712868Z","steps":["trace[1261393635] 'agreement among raft nodes before linearized reading' (duration: 146.874419ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.713524Z","caller":"traceutil/trace.go:171","msg":"trace[281505509] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"132.38672ms","start":"2026-07-24T00:41:05.581115Z","end":"2026-07-24T00:41:05.713502Z","steps":["trace[281505509] 'process raft request' (duration: 132.119645ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.713674Z","caller":"traceutil/trace.go:171","msg":"trace[2000550602] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"123.323677ms","start":"2026-07-24T00:41:05.590331Z","end":"2026-07-24T00:41:05.713655Z","steps":["trace[2000550602] 'process raft request' (duration: 123.144684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:05.713719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.027842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T00:41:05.71382Z","caller":"traceutil/trace.go:171","msg":"trace[801434164] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"131.950642ms","start":"2026-07-24T00:41:05.581858Z","end":"2026-07-24T00:41:05.713808Z","steps":["trace[801434164] 'process raft request' (duration: 131.559875ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:05.713858Z","caller":"traceutil/trace.go:171","msg":"trace[143211743] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25118; }","duration":"147.204535ms","start":"2026-07-24T00:41:05.566625Z","end":"2026-07-24T00:41:05.713829Z","steps":["trace[143211743] 'agreement among raft nodes before linearized reading' (duration: 146.915349ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:06.601424Z","caller":"traceutil/trace.go:171","msg":"trace[1132849193] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"158.066404ms","start":"2026-07-24T00:41:06.443335Z","end":"2026-07-24T00:41:06.601402Z","steps":["trace[1132849193] 'process raft request' (duration: 157.933741ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:06.601839Z","caller":"traceutil/trace.go:171","msg":"trace[912874525] linearizableReadLoop","detail":"{readStateIndex:25957; appliedIndex:25957; }","duration":"157.911301ms","start":"2026-07-24T00:41:06.44392Z","end":"2026-07-24T00:41:06.601831Z","steps":["trace[912874525] 'read index received' (duration: 157.908661ms)","trace[912874525] 'applied index is now lower than readState.Index' (duration: 2.07µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:06.601911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.985062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:06.601929Z","caller":"traceutil/trace.go:171","msg":"trace[1686787146] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25126; }","duration":"158.041313ms","start":"2026-07-24T00:41:06.443882Z","end":"2026-07-24T00:41:06.601923Z","steps":["trace[1686787146] 'agreement among raft nodes before linearized reading' (duration: 157.989392ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:06.716826Z","caller":"traceutil/trace.go:171","msg":"trace[1745972968] linearizableReadLoop","detail":"{readStateIndex:25958; appliedIndex:25957; }","duration":"114.943327ms","start":"2026-07-24T00:41:06.601853Z","end":"2026-07-24T00:41:06.716796Z","steps":["trace[1745972968] 'read index received' (duration: 114.739203ms)","trace[1745972968] 'applied index is now lower than readState.Index' (duration: 203.134µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:06.716976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.479339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:06.71701Z","caller":"traceutil/trace.go:171","msg":"trace[2087654745] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25127; }","duration":"262.58875ms","start":"2026-07-24T00:41:06.45441Z","end":"2026-07-24T00:41:06.716998Z","steps":["trace[2087654745] 'agreement among raft nodes before linearized reading' (duration: 262.493068ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:06.717118Z","caller":"traceutil/trace.go:171","msg":"trace[1348066258] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"196.620594ms","start":"2026-07-24T00:41:06.520465Z","end":"2026-07-24T00:41:06.717085Z","steps":["trace[1348066258] 'process raft request' (duration: 196.154226ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:08.607559Z","caller":"traceutil/trace.go:171","msg":"trace[847859437] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"123.850268ms","start":"2026-07-24T00:41:08.483666Z","end":"2026-07-24T00:41:08.607516Z","steps":["trace[847859437] 'process raft request' (duration: 123.583153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:10.167096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.157124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:41:10.167221Z","caller":"traceutil/trace.go:171","msg":"trace[1776963847] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25166; }","duration":"173.343457ms","start":"2026-07-24T00:41:09.993849Z","end":"2026-07-24T00:41:10.167193Z","steps":["trace[1776963847] 'count revisions from in-memory index tree' (duration: 173.014542ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:10.245431Z","caller":"traceutil/trace.go:171","msg":"trace[866497468] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"188.981428ms","start":"2026-07-24T00:41:10.05643Z","end":"2026-07-24T00:41:10.245411Z","steps":["trace[866497468] 'process raft request' (duration: 188.778904ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:11.490397Z","caller":"traceutil/trace.go:171","msg":"trace[982003196] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"132.512993ms","start":"2026-07-24T00:41:11.357849Z","end":"2026-07-24T00:41:11.490362Z","steps":["trace[982003196] 'process raft request' (duration: 124.870746ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:11.490425Z","caller":"traceutil/trace.go:171","msg":"trace[708015122] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"131.979983ms","start":"2026-07-24T00:41:11.358421Z","end":"2026-07-24T00:41:11.490401Z","steps":["trace[708015122] 'process raft request' (duration: 131.882531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:35.151718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.674648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-07-24T00:41:35.151807Z","caller":"traceutil/trace.go:171","msg":"trace[266923833] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25378; }","duration":"132.837661ms","start":"2026-07-24T00:41:35.01895Z","end":"2026-07-24T00:41:35.151788Z","steps":["trace[266923833] 'range keys from in-memory index tree' (duration: 132.452414ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:35.405058Z","caller":"traceutil/trace.go:171","msg":"trace[2009947837] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"130.812371ms","start":"2026-07-24T00:41:35.274195Z","end":"2026-07-24T00:41:35.405007Z","steps":["trace[2009947837] 'process raft request' (duration: 130.609727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:35.777555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.275791ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T00:41:35.777732Z","caller":"traceutil/trace.go:171","msg":"trace[1176428574] linearizableReadLoop","detail":"{readStateIndex:26217; appliedIndex:26216; }","duration":"278.71695ms","start":"2026-07-24T00:41:35.498978Z","end":"2026-07-24T00:41:35.777695Z","steps":["trace[1176428574] 'read index received' (duration: 24.190583ms)","trace[1176428574] 'applied index is now lower than readState.Index' (duration: 254.524217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:35.77801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.937634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-24T00:41:35.777999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.991205ms","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-24T00:41:35.778048Z","caller":"traceutil/trace.go:171","msg":"trace[2128034097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25379; }","duration":"167.028876ms","start":"2026-07-24T00:41:35.611007Z","end":"2026-07-24T00:41:35.778036Z","steps":["trace[2128034097] 'agreement among raft nodes before linearized reading' (duration: 166.842383ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:35.778091Z","caller":"traceutil/trace.go:171","msg":"trace[590920153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25379; }","duration":"279.138938ms","start":"2026-07-24T00:41:35.498929Z","end":"2026-07-24T00:41:35.778068Z","steps":["trace[590920153] 'agreement among raft nodes before linearized reading' (duration: 278.863823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:35.77844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.340414ms","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-24T00:41:35.778553Z","caller":"traceutil/trace.go:171","msg":"trace[527392706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25379; }","duration":"165.555088ms","start":"2026-07-24T00:41:35.612971Z","end":"2026-07-24T00:41:35.778526Z","steps":["trace[527392706] 'agreement among raft nodes before linearized reading' (duration: 165.146661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.03556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:36.035728Z","caller":"traceutil/trace.go:171","msg":"trace[700004849] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"252.085729ms","start":"2026-07-24T00:41:35.783619Z","end":"2026-07-24T00:41:36.035704Z","steps":["trace[700004849] 'process raft request' (duration: 252.035818ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.035749Z","caller":"traceutil/trace.go:171","msg":"trace[1005193159] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"252.587878ms","start":"2026-07-24T00:41:35.783129Z","end":"2026-07-24T00:41:36.035717Z","steps":["trace[1005193159] 'process raft request' (duration: 145.169797ms)","trace[1005193159] 'compare' (duration: 106.880591ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:36.082345Z","caller":"traceutil/trace.go:171","msg":"trace[989195477] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"209.565973ms","start":"2026-07-24T00:41:35.872717Z","end":"2026-07-24T00:41:36.082283Z","steps":["trace[989195477] 'process raft request' (duration: 209.282518ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.082357Z","caller":"traceutil/trace.go:171","msg":"trace[1516451198] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"184.405521ms","start":"2026-07-24T00:41:35.897915Z","end":"2026-07-24T00:41:36.082321Z","steps":["trace[1516451198] 'process raft request' (duration: 184.255068ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.082894Z","caller":"traceutil/trace.go:171","msg":"trace[1128173145] linearizableReadLoop","detail":"{readStateIndex:26225; appliedIndex:26219; }","duration":"179.251961ms","start":"2026-07-24T00:41:35.90361Z","end":"2026-07-24T00:41:36.082862Z","steps":["trace[1128173145] 'read index received' (duration: 24.698704ms)","trace[1128173145] 'applied index is now lower than readState.Index' (duration: 154.551927ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:36.083106Z","caller":"traceutil/trace.go:171","msg":"trace[1303290050] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"183.425271ms","start":"2026-07-24T00:41:35.899662Z","end":"2026-07-24T00:41:36.083087Z","steps":["trace[1303290050] 'process raft request' (duration: 182.589085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.083276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.638198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-24T00:41:36.083329Z","caller":"traceutil/trace.go:171","msg":"trace[244408236] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25388; }","duration":"179.74415ms","start":"2026-07-24T00:41:35.90357Z","end":"2026-07-24T00:41:36.083314Z","steps":["trace[244408236] 'agreement among raft nodes before linearized reading' (duration: 179.516456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.083422Z","caller":"traceutil/trace.go:171","msg":"trace[360493816] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"181.467764ms","start":"2026-07-24T00:41:35.901936Z","end":"2026-07-24T00:41:36.083403Z","steps":["trace[360493816] 'process raft request' (duration: 180.673629ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.083438Z","caller":"traceutil/trace.go:171","msg":"trace[1520193202] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"161.960799ms","start":"2026-07-24T00:41:35.921465Z","end":"2026-07-24T00:41:36.083426Z","steps":["trace[1520193202] 'process raft request' (duration: 161.270486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.083564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.607551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-07-24T00:41:36.083653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.762999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:41:36.083699Z","caller":"traceutil/trace.go:171","msg":"trace[59139267] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25388; }","duration":"132.85458ms","start":"2026-07-24T00:41:35.950827Z","end":"2026-07-24T00:41:36.083681Z","steps":["trace[59139267] 'agreement among raft nodes before linearized reading' (duration: 132.748408ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.083661Z","caller":"traceutil/trace.go:171","msg":"trace[1941033833] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25388; }","duration":"161.762845ms","start":"2026-07-24T00:41:35.921872Z","end":"2026-07-24T00:41:36.083635Z","steps":["trace[1941033833] 'agreement among raft nodes before linearized reading' (duration: 161.506661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.083831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.950647ms","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-24T00:41:36.083894Z","caller":"traceutil/trace.go:171","msg":"trace[668196852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25388; }","duration":"139.09215ms","start":"2026-07-24T00:41:35.944789Z","end":"2026-07-24T00:41:36.083881Z","steps":["trace[668196852] 'agreement among raft nodes before linearized reading' (duration: 138.865256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.521333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.205246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:36.521536Z","caller":"traceutil/trace.go:171","msg":"trace[302101648] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"433.713026ms","start":"2026-07-24T00:41:36.087793Z","end":"2026-07-24T00:41:36.521506Z","steps":["trace[302101648] 'process raft request' (duration: 186.254334ms)","trace[302101648] 'compare' (duration: 246.594784ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:36.52161Z","caller":"traceutil/trace.go:171","msg":"trace[1169192314] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"216.956545ms","start":"2026-07-24T00:41:36.304638Z","end":"2026-07-24T00:41:36.521595Z","steps":["trace[1169192314] 'process raft request' (duration: 216.925905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.521692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.08777Z","time spent":"433.836228ms","remote":"127.0.0.1:60132","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-24T00:41:36.521723Z","caller":"traceutil/trace.go:171","msg":"trace[1293385180] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26226; }","duration":"395.283357ms","start":"2026-07-24T00:41:36.126434Z","end":"2026-07-24T00:41:36.521718Z","steps":["trace[1293385180] 'read index received' (duration: 147.608412ms)","trace[1293385180] 'applied index is now lower than readState.Index' (duration: 247.674185ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:36.52169Z","caller":"traceutil/trace.go:171","msg":"trace[479267521] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"433.321318ms","start":"2026-07-24T00:41:36.088328Z","end":"2026-07-24T00:41:36.52165Z","steps":["trace[479267521] 'process raft request' (duration: 433.182296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.521823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.39005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:36.521837Z","caller":"traceutil/trace.go:171","msg":"trace[1350520270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25393; }","duration":"395.447412ms","start":"2026-07-24T00:41:36.126385Z","end":"2026-07-24T00:41:36.521832Z","steps":["trace[1350520270] 'agreement among raft nodes before linearized reading' (duration: 395.37397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.521858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.126367Z","time spent":"395.480973ms","remote":"127.0.0.1:59880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T00:41:36.521705Z","caller":"traceutil/trace.go:171","msg":"trace[1404117346] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"432.768658ms","start":"2026-07-24T00:41:36.088931Z","end":"2026-07-24T00:41:36.5217Z","steps":["trace[1404117346] 'process raft request' (duration: 432.599965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.52188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.088306Z","time spent":"433.476772ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:36.521945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.088924Z","time spent":"432.992772ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:36.521915Z","caller":"traceutil/trace.go:171","msg":"trace[1942397878] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"433.801218ms","start":"2026-07-24T00:41:36.08809Z","end":"2026-07-24T00:41:36.521891Z","steps":["trace[1942397878] 'process raft request' (duration: 433.339919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.522071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.088064Z","time spent":"433.917549ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:36.60074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.133021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:36.600842Z","caller":"traceutil/trace.go:171","msg":"trace[635539877] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25394; }","duration":"363.295245ms","start":"2026-07-24T00:41:36.237524Z","end":"2026-07-24T00:41:36.600819Z","steps":["trace[635539877] 'agreement among raft nodes before linearized reading' (duration: 363.104681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.600837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.523134ms","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-24T00:41:36.600962Z","caller":"traceutil/trace.go:171","msg":"trace[1490168800] 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:25394; }","duration":"182.698357ms","start":"2026-07-24T00:41:36.418234Z","end":"2026-07-24T00:41:36.600932Z","steps":["trace[1490168800] 'agreement among raft nodes before linearized reading' (duration: 182.34902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:36.600892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:36.237503Z","time spent":"363.377246ms","remote":"127.0.0.1:46456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:41:36.600736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.758603ms","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-24T00:41:36.601274Z","caller":"traceutil/trace.go:171","msg":"trace[330615377] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25394; }","duration":"277.270983ms","start":"2026-07-24T00:41:36.323918Z","end":"2026-07-24T00:41:36.601189Z","steps":["trace[330615377] 'agreement among raft nodes before linearized reading' (duration: 276.667391ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:36.601121Z","caller":"traceutil/trace.go:171","msg":"trace[1369106676] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"184.246427ms","start":"2026-07-24T00:41:36.416854Z","end":"2026-07-24T00:41:36.601101Z","steps":["trace[1369106676] 'process raft request' (duration: 183.440842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.150661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.250627ms","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-24T00:41:37.150777Z","caller":"traceutil/trace.go:171","msg":"trace[527076466] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25395; }","duration":"183.433851ms","start":"2026-07-24T00:41:36.967318Z","end":"2026-07-24T00:41:37.150751Z","steps":["trace[527076466] 'range keys from in-memory index tree' (duration: 183.048764ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:37.674332Z","caller":"traceutil/trace.go:171","msg":"trace[501839777] linearizableReadLoop","detail":"{readStateIndex:26234; appliedIndex:26233; }","duration":"219.795148ms","start":"2026-07-24T00:41:37.454504Z","end":"2026-07-24T00:41:37.674299Z","steps":["trace[501839777] 'read index received' (duration: 219.500723ms)","trace[501839777] 'applied index is now lower than readState.Index' (duration: 293.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:37.674468Z","caller":"traceutil/trace.go:171","msg":"trace[2056655412] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"263.800245ms","start":"2026-07-24T00:41:37.410618Z","end":"2026-07-24T00:41:37.674418Z","steps":["trace[2056655412] 'process raft request' (duration: 263.496369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.674689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.052604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:37.674779Z","caller":"traceutil/trace.go:171","msg":"trace[308362935] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25396; }","duration":"220.313599ms","start":"2026-07-24T00:41:37.454443Z","end":"2026-07-24T00:41:37.674756Z","steps":["trace[308362935] 'agreement among raft nodes before linearized reading' (duration: 220.066944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.674777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.609752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:41:37.674879Z","caller":"traceutil/trace.go:171","msg":"trace[27037328] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25396; }","duration":"187.774505ms","start":"2026-07-24T00:41:37.487079Z","end":"2026-07-24T00:41:37.674853Z","steps":["trace[27037328] 'agreement among raft nodes before linearized reading' (duration: 187.606242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.675029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.096375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:37.675142Z","caller":"traceutil/trace.go:171","msg":"trace[1733108459] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25396; }","duration":"120.282559ms","start":"2026-07-24T00:41:37.55483Z","end":"2026-07-24T00:41:37.675113Z","steps":["trace[1733108459] 'agreement among raft nodes before linearized reading' (duration: 120.052954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.944764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.787701ms","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-24T00:41:37.944871Z","caller":"traceutil/trace.go:171","msg":"trace[1609211819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25396; }","duration":"143.912353ms","start":"2026-07-24T00:41:37.800936Z","end":"2026-07-24T00:41:37.944848Z","steps":["trace[1609211819] 'range keys from in-memory index tree' (duration: 143.536816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:37.945094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.350871ms","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-24T00:41:37.945191Z","caller":"traceutil/trace.go:171","msg":"trace[511394821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25396; }","duration":"143.544906ms","start":"2026-07-24T00:41:37.801621Z","end":"2026-07-24T00:41:37.945166Z","steps":["trace[511394821] 'range keys from in-memory index tree' (duration: 143.113157ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.12012Z","caller":"traceutil/trace.go:171","msg":"trace[986000773] linearizableReadLoop","detail":"{readStateIndex:26236; appliedIndex:26234; }","duration":"110.408819ms","start":"2026-07-24T00:41:38.009681Z","end":"2026-07-24T00:41:38.12009Z","steps":["trace[986000773] 'read index received' (duration: 31.051815ms)","trace[986000773] 'applied index is now lower than readState.Index' (duration: 79.355724ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:38.120238Z","caller":"traceutil/trace.go:171","msg":"trace[530347685] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"170.641536ms","start":"2026-07-24T00:41:37.949566Z","end":"2026-07-24T00:41:38.120208Z","steps":["trace[530347685] 'process raft request' (duration: 156.474714ms)","trace[530347685] 'compare' (duration: 13.798895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:38.120302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.592783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:38.120341Z","caller":"traceutil/trace.go:171","msg":"trace[294113818] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25398; }","duration":"110.659465ms","start":"2026-07-24T00:41:38.00967Z","end":"2026-07-24T00:41:38.12033Z","steps":["trace[294113818] 'agreement among raft nodes before linearized reading' (duration: 110.550032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.452764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.346772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:38.453219Z","caller":"traceutil/trace.go:171","msg":"trace[857976299] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"363.597969ms","start":"2026-07-24T00:41:38.089601Z","end":"2026-07-24T00:41:38.453199Z","steps":["trace[857976299] 'process raft request' (duration: 363.365725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.45329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.089561Z","time spent":"363.698161ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:38.453273Z","caller":"traceutil/trace.go:171","msg":"trace[181315888] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"365.014256ms","start":"2026-07-24T00:41:38.088229Z","end":"2026-07-24T00:41:38.453243Z","steps":["trace[181315888] 'process raft request' (duration: 364.668469ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.453276Z","caller":"traceutil/trace.go:171","msg":"trace[2057787511] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"365.059046ms","start":"2026-07-24T00:41:38.088156Z","end":"2026-07-24T00:41:38.453215Z","steps":["trace[2057787511] 'process raft request' (duration: 147.154574ms)","trace[2057787511] 'compare' (duration: 217.051856ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:38.453293Z","caller":"traceutil/trace.go:171","msg":"trace[1592181447] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26236; }","duration":"333.057383ms","start":"2026-07-24T00:41:38.120192Z","end":"2026-07-24T00:41:38.453249Z","steps":["trace[1592181447] 'read index received' (duration: 115.073779ms)","trace[1592181447] 'applied index is now lower than readState.Index' (duration: 217.981074ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:38.453328Z","caller":"traceutil/trace.go:171","msg":"trace[1154077020] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"363.63982ms","start":"2026-07-24T00:41:38.089653Z","end":"2026-07-24T00:41:38.453293Z","steps":["trace[1154077020] 'process raft request' (duration: 363.347034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.453387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.088207Z","time spent":"365.119637ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:38.453466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.479617ms","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-24T00:41:38.453493Z","caller":"traceutil/trace.go:171","msg":"trace[1557948701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25402; }","duration":"413.534427ms","start":"2026-07-24T00:41:38.039947Z","end":"2026-07-24T00:41:38.453482Z","steps":["trace[1557948701] 'agreement among raft nodes before linearized reading' (duration: 413.435185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.453495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.088122Z","time spent":"365.24231ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:41:38.453501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.08962Z","time spent":"363.789963ms","remote":"127.0.0.1:60132","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-24T00:41:38.453522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.039933Z","time spent":"413.580089ms","remote":"127.0.0.1:60132","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-24T00:41:38.453588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.875735ms","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-24T00:41:38.453633Z","caller":"traceutil/trace.go:171","msg":"trace[160327401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25402; }","duration":"413.987667ms","start":"2026-07-24T00:41:38.039633Z","end":"2026-07-24T00:41:38.45362Z","steps":["trace[160327401] 'agreement among raft nodes before linearized reading' (duration: 413.795573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.45367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.039606Z","time spent":"414.054959ms","remote":"127.0.0.1:60132","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-24T00:41:38.45369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.599677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-24T00:41:38.453584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.940986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:38.453728Z","caller":"traceutil/trace.go:171","msg":"trace[1701621255] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25402; }","duration":"154.683969ms","start":"2026-07-24T00:41:38.299033Z","end":"2026-07-24T00:41:38.453717Z","steps":["trace[1701621255] 'agreement among raft nodes before linearized reading' (duration: 154.580527ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.453744Z","caller":"traceutil/trace.go:171","msg":"trace[1539781460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25402; }","duration":"326.1614ms","start":"2026-07-24T00:41:38.127567Z","end":"2026-07-24T00:41:38.453728Z","steps":["trace[1539781460] 'agreement among raft nodes before linearized reading' (duration: 325.955086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.45378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.127543Z","time spent":"326.228022ms","remote":"127.0.0.1:59880","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-24T00:41:38.806392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.628476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:38.806651Z","caller":"traceutil/trace.go:171","msg":"trace[51213171] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"198.589363ms","start":"2026-07-24T00:41:38.608042Z","end":"2026-07-24T00:41:38.806631Z","steps":["trace[51213171] 'process raft request' (duration: 198.547132ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.806774Z","caller":"traceutil/trace.go:171","msg":"trace[1897783023] linearizableReadLoop","detail":"{readStateIndex:26242; appliedIndex:26240; }","duration":"280.597496ms","start":"2026-07-24T00:41:38.526167Z","end":"2026-07-24T00:41:38.806764Z","steps":["trace[1897783023] 'read index received' (duration: 72.542873ms)","trace[1897783023] 'applied index is now lower than readState.Index' (duration: 208.053573ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:38.806901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.128788ms","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-24T00:41:38.806874Z","caller":"traceutil/trace.go:171","msg":"trace[170013464] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"345.953981ms","start":"2026-07-24T00:41:38.460882Z","end":"2026-07-24T00:41:38.806836Z","steps":["trace[170013464] 'process raft request' (duration: 137.772465ms)","trace[170013464] 'compare' (duration: 207.248868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:38.806909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.275972ms","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":"info","ts":"2026-07-24T00:41:38.806929Z","caller":"traceutil/trace.go:171","msg":"trace[182583440] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25405; }","duration":"254.154718ms","start":"2026-07-24T00:41:38.552761Z","end":"2026-07-24T00:41:38.806916Z","steps":["trace[182583440] 'agreement among raft nodes before linearized reading' (duration: 254.104077ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.80695Z","caller":"traceutil/trace.go:171","msg":"trace[412584725] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25405; }","duration":"154.371414ms","start":"2026-07-24T00:41:38.652569Z","end":"2026-07-24T00:41:38.806941Z","steps":["trace[412584725] 'agreement among raft nodes before linearized reading' (duration: 154.294902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.807028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.164868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-24T00:41:38.806998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.81302ms","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-24T00:41:38.807048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.46085Z","time spent":"346.096714ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:38.80705Z","caller":"traceutil/trace.go:171","msg":"trace[408999238] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"343.568865ms","start":"2026-07-24T00:41:38.463467Z","end":"2026-07-24T00:41:38.807036Z","steps":["trace[408999238] 'process raft request' (duration: 343.044965ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:38.807074Z","caller":"traceutil/trace.go:171","msg":"trace[1038354997] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25405; }","duration":"280.26784ms","start":"2026-07-24T00:41:38.526791Z","end":"2026-07-24T00:41:38.807059Z","steps":["trace[1038354997] 'agreement among raft nodes before linearized reading' (duration: 280.076516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.807046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.391486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:38.807098Z","caller":"traceutil/trace.go:171","msg":"trace[1102388270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25405; }","duration":"280.961403ms","start":"2026-07-24T00:41:38.526114Z","end":"2026-07-24T00:41:38.807076Z","steps":["trace[1102388270] 'agreement among raft nodes before linearized reading' (duration: 280.717778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:38.807118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:41:38.463446Z","time spent":"343.641376ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:41:38.807134Z","caller":"traceutil/trace.go:171","msg":"trace[1628870507] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25405; }","duration":"262.542729ms","start":"2026-07-24T00:41:38.544569Z","end":"2026-07-24T00:41:38.807112Z","steps":["trace[1628870507] 'agreement among raft nodes before linearized reading' (duration: 262.363455ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:39.082289Z","caller":"traceutil/trace.go:171","msg":"trace[436076699] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"270.988712ms","start":"2026-07-24T00:41:38.81126Z","end":"2026-07-24T00:41:39.082248Z","steps":["trace[436076699] 'process raft request' (duration: 216.040287ms)","trace[436076699] 'compare' (duration: 54.649249ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:39.082396Z","caller":"traceutil/trace.go:171","msg":"trace[268782415] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"153.721891ms","start":"2026-07-24T00:41:38.928641Z","end":"2026-07-24T00:41:39.082363Z","steps":["trace[268782415] 'process raft request' (duration: 153.577738ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:39.082492Z","caller":"traceutil/trace.go:171","msg":"trace[1799225142] linearizableReadLoop","detail":"{readStateIndex:26245; appliedIndex:26243; }","duration":"267.906533ms","start":"2026-07-24T00:41:38.814554Z","end":"2026-07-24T00:41:39.08246Z","steps":["trace[1799225142] 'read index received' (duration: 57.749619ms)","trace[1799225142] 'applied index is now lower than readState.Index' (duration: 210.153724ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:39.082754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.188518ms","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-24T00:41:39.082784Z","caller":"traceutil/trace.go:171","msg":"trace[912922984] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25408; }","duration":"268.27592ms","start":"2026-07-24T00:41:38.814499Z","end":"2026-07-24T00:41:39.082774Z","steps":["trace[912922984] 'agreement among raft nodes before linearized reading' (duration: 268.108937ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:40.930441Z","caller":"traceutil/trace.go:171","msg":"trace[619071125] linearizableReadLoop","detail":"{readStateIndex:26256; appliedIndex:26255; }","duration":"119.080065ms","start":"2026-07-24T00:41:40.811321Z","end":"2026-07-24T00:41:40.930401Z","steps":["trace[619071125] 'read index received' (duration: 118.891312ms)","trace[619071125] 'applied index is now lower than readState.Index' (duration: 187.133µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:40.930515Z","caller":"traceutil/trace.go:171","msg":"trace[359009990] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"145.959242ms","start":"2026-07-24T00:41:40.78451Z","end":"2026-07-24T00:41:40.930469Z","steps":["trace[359009990] 'process raft request' (duration: 145.558044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:40.931143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.734408ms","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-24T00:41:40.931398Z","caller":"traceutil/trace.go:171","msg":"trace[776510853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25417; }","duration":"119.949343ms","start":"2026-07-24T00:41:40.81127Z","end":"2026-07-24T00:41:40.931219Z","steps":["trace[776510853] 'agreement among raft nodes before linearized reading' (duration: 119.30733ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:41.099038Z","caller":"traceutil/trace.go:171","msg":"trace[179434407] linearizableReadLoop","detail":"{readStateIndex:26257; appliedIndex:26256; }","duration":"168.466264ms","start":"2026-07-24T00:41:40.930527Z","end":"2026-07-24T00:41:41.098994Z","steps":["trace[179434407] 'read index received' (duration: 102.369795ms)","trace[179434407] 'applied index is now lower than readState.Index' (duration: 66.095439ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:41.099085Z","caller":"traceutil/trace.go:171","msg":"trace[2101826854] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"287.133142ms","start":"2026-07-24T00:41:40.811911Z","end":"2026-07-24T00:41:41.099045Z","steps":["trace[2101826854] 'process raft request' (duration: 220.955282ms)","trace[2101826854] 'compare' (duration: 65.984376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:41:41.099557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.245374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:41.099593Z","caller":"traceutil/trace.go:171","msg":"trace[42016669] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25418; }","duration":"224.346916ms","start":"2026-07-24T00:41:40.875234Z","end":"2026-07-24T00:41:41.099581Z","steps":["trace[42016669] 'agreement among raft nodes before linearized reading' (duration: 224.260675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:41.099847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.452837ms","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-24T00:41:41.099869Z","caller":"traceutil/trace.go:171","msg":"trace[706361490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25418; }","duration":"288.526809ms","start":"2026-07-24T00:41:40.811336Z","end":"2026-07-24T00:41:41.099863Z","steps":["trace[706361490] 'agreement among raft nodes before linearized reading' (duration: 288.403426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:41.100726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.410149ms","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-24T00:41:41.100851Z","caller":"traceutil/trace.go:171","msg":"trace[1422458594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25418; }","duration":"223.614753ms","start":"2026-07-24T00:41:40.877203Z","end":"2026-07-24T00:41:41.100818Z","steps":["trace[1422458594] 'agreement among raft nodes before linearized reading' (duration: 221.962081ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:41.100999Z","caller":"traceutil/trace.go:171","msg":"trace[243157499] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"164.110789ms","start":"2026-07-24T00:41:40.936644Z","end":"2026-07-24T00:41:41.100755Z","steps":["trace[243157499] 'process raft request' (duration: 163.942236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:41.396837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.40876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:41:41.397022Z","caller":"traceutil/trace.go:171","msg":"trace[1765632012] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"292.786681ms","start":"2026-07-24T00:41:41.104214Z","end":"2026-07-24T00:41:41.397Z","steps":["trace[1765632012] 'process raft request' (duration: 170.121977ms)","trace[1765632012] 'compare' (duration: 121.842799ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:41.397224Z","caller":"traceutil/trace.go:171","msg":"trace[1764717083] linearizableReadLoop","detail":"{readStateIndex:26261; appliedIndex:26259; }","duration":"236.820686ms","start":"2026-07-24T00:41:41.160366Z","end":"2026-07-24T00:41:41.397186Z","steps":["trace[1764717083] 'read index received' (duration: 113.913747ms)","trace[1764717083] 'applied index is now lower than readState.Index' (duration: 122.904249ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:41:41.397272Z","caller":"traceutil/trace.go:171","msg":"trace[158797293] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"290.268503ms","start":"2026-07-24T00:41:41.106963Z","end":"2026-07-24T00:41:41.397232Z","steps":["trace[158797293] 'process raft request' (duration: 289.988198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:41.397428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.472411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:41.397535Z","caller":"traceutil/trace.go:171","msg":"trace[521080973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25422; }","duration":"271.605873ms","start":"2026-07-24T00:41:41.125909Z","end":"2026-07-24T00:41:41.397515Z","steps":["trace[521080973] 'agreement among raft nodes before linearized reading' (duration: 271.387219ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:41.400395Z","caller":"traceutil/trace.go:171","msg":"trace[1371596161] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"233.746077ms","start":"2026-07-24T00:41:41.166625Z","end":"2026-07-24T00:41:41.400371Z","steps":["trace[1371596161] 'process raft request' (duration: 233.567384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:41:41.40065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.402533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:41:41.400721Z","caller":"traceutil/trace.go:171","msg":"trace[831999711] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25423; }","duration":"216.524866ms","start":"2026-07-24T00:41:41.184176Z","end":"2026-07-24T00:41:41.400701Z","steps":["trace[831999711] 'agreement among raft nodes before linearized reading' (duration: 216.395454ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:54.308665Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006700.wal"} {"level":"info","ts":"2026-07-24T00:41:57.347711Z","caller":"traceutil/trace.go:171","msg":"trace[1291282538] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"132.968958ms","start":"2026-07-24T00:41:57.214711Z","end":"2026-07-24T00:41:57.347679Z","steps":["trace[1291282538] 'process raft request' (duration: 128.752117ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:41:57.400132Z","caller":"traceutil/trace.go:171","msg":"trace[2049868016] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"185.152295ms","start":"2026-07-24T00:41:57.214951Z","end":"2026-07-24T00:41:57.400104Z","steps":["trace[2049868016] 'process raft request' (duration: 184.985601ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:42:17.641235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22853} {"level":"info","ts":"2026-07-24T00:42:17.790517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22853,"took":"145.320623ms","hash":2690732043,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":35565568,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-24T00:42:17.790587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2690732043,"revision":22853,"compact-revision":20000} {"level":"info","ts":"2026-07-24T00:47:17.647909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25696} {"level":"info","ts":"2026-07-24T00:47:17.793488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25696,"took":"140.48131ms","hash":572275418,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":37552128,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-24T00:47:17.793578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":572275418,"revision":25696,"compact-revision":22853} {"level":"info","ts":"2026-07-24T00:48:25.798786Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:48:25.803573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-24T00:48:25.803703Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-24T00:51:06.766024Z","caller":"traceutil/trace.go:171","msg":"trace[1423392312] transaction","detail":"{read_only:false; response_revision:30272; number_of_response:1; }","duration":"114.022015ms","start":"2026-07-24T00:51:06.651976Z","end":"2026-07-24T00:51:06.765998Z","steps":["trace[1423392312] 'process raft request' (duration: 30.622704ms)","trace[1423392312] 'compare' (duration: 83.056734ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:52:17.655002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28568} {"level":"info","ts":"2026-07-24T00:52:17.80224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28568,"took":"141.599042ms","hash":1140991431,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T00:52:17.802326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1140991431,"revision":28568,"compact-revision":25696} {"level":"info","ts":"2026-07-24T00:52:57.521613Z","caller":"traceutil/trace.go:171","msg":"trace[198866804] transaction","detail":"{read_only:false; response_revision:31081; number_of_response:1; }","duration":"140.266967ms","start":"2026-07-24T00:52:57.381312Z","end":"2026-07-24T00:52:57.521579Z","steps":["trace[198866804] 'process raft request' (duration: 86.460459ms)","trace[198866804] 'compare' (duration: 53.627093ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:57:17.663957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30791} {"level":"info","ts":"2026-07-24T00:57:17.780668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30791,"took":"112.502946ms","hash":2247948747,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T00:57:17.780717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247948747,"revision":30791,"compact-revision":28568} {"level":"info","ts":"2026-07-24T01:02:17.669614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33052} {"level":"info","ts":"2026-07-24T01:02:17.816828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33052,"took":"142.341181ms","hash":1260043867,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T01:02:17.816892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1260043867,"revision":33052,"compact-revision":30791} {"level":"info","ts":"2026-07-24T01:07:17.676426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35409} {"level":"info","ts":"2026-07-24T01:07:17.820474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35409,"took":"138.779143ms","hash":1435975792,"current-db-size-bytes":59674624,"current-db-size":"60 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T01:07:17.820547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1435975792,"revision":35409,"compact-revision":33052} {"level":"info","ts":"2026-07-24T01:09:44.918961Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T01:09:44.924322Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-24T01:09:44.924507Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-24T01:12:17.682413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37668} {"level":"info","ts":"2026-07-24T01:12:17.799008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37668,"took":"112.692447ms","hash":1970298318,"current-db-size-bytes":61366272,"current-db-size":"61 MB","current-db-size-in-use-bytes":32575488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T01:12:17.799058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1970298318,"revision":37668,"compact-revision":35409}