{"level":"warn","ts":"2026-08-11T11:06:54.082011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:06:54.082099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T11:06:54.082158Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:06:54.082165Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-11T11:06:54.082184Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T11:06:54.082776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-08-11T11:06:54.082846Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T11:06:54.085617Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.528989ms"} {"level":"info","ts":"2026-08-11T11:06:54.091381Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-08-11T11:06:54.091438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:06:54.091456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-08-11T11:06:54.091463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:06:54.09147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-08-11T11:06:54.091498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-08-11T11:06:54.100589Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:06:54.102502Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:06:54.104417Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-11T11:06:54.108836Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:06:54.109054Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:06:54.109189Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:06:54.10921Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:06:54.109122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:06:54.110624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-08-11T11:06:54.110791Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-11T11:06:54.114074Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T11:06:54.11417Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-11T11:06:54.11423Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-11T11:06:54.114319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:06:54.11435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:06:54.992434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:06:54.992497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:06:54.99254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-08-11T11:06:54.992561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:06:54.992572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-11T11:06:54.992587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-08-11T11:06:54.992602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-11T11:06:54.993524Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:06:54.993665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:06:54.99369Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:06:54.994016Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:54.995Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:54.995085Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:54.995112Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:54.996514Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:06:54.996573Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:06:54.997644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-08-11T11:06:55.003978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-11T11:07:33.489826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.753385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:33.490326Z","caller":"traceutil/trace.go:171","msg":"trace[5250056] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"207.803493ms","start":"2026-08-11T11:07:33.282486Z","end":"2026-08-11T11:07:33.490289Z","steps":["trace[5250056] 'process raft request' (duration: 76.349673ms)","trace[5250056] 'compare' (duration: 129.281356ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:33.490828Z","caller":"traceutil/trace.go:171","msg":"trace[149404121] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"204.035206ms","start":"2026-08-11T11:07:33.286739Z","end":"2026-08-11T11:07:33.490774Z","steps":["trace[149404121] 'process raft request' (duration: 203.562337ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:33.490979Z","caller":"traceutil/trace.go:171","msg":"trace[1633598334] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"201.980375ms","start":"2026-08-11T11:07:33.288966Z","end":"2026-08-11T11:07:33.490947Z","steps":["trace[1633598334] 'process raft request' (duration: 201.663659ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:33.491548Z","caller":"traceutil/trace.go:171","msg":"trace[1406847947] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:576; }","duration":"105.264784ms","start":"2026-08-11T11:07:33.386262Z","end":"2026-08-11T11:07:33.491527Z","steps":["trace[1406847947] 'read index received' (duration: 102.05207ms)","trace[1406847947] 'applied index is now lower than readState.Index' (duration: 3.210194ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:33.491593Z","caller":"traceutil/trace.go:171","msg":"trace[2046953305] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"192.178699ms","start":"2026-08-11T11:07:33.299396Z","end":"2026-08-11T11:07:33.491575Z","steps":["trace[2046953305] 'process raft request' (duration: 191.396314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:33.491783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.491408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T11:07:33.492096Z","caller":"traceutil/trace.go:171","msg":"trace[366966900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"105.839666ms","start":"2026-08-11T11:07:33.386207Z","end":"2026-08-11T11:07:33.492047Z","steps":["trace[366966900] 'agreement among raft nodes before linearized reading' (duration: 105.425027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.032378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054640893563013,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:07:40.298302Z","caller":"traceutil/trace.go:171","msg":"trace[764681973] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"766.43005ms","start":"2026-08-11T11:07:39.53184Z","end":"2026-08-11T11:07:40.298271Z","steps":["trace[764681973] 'read index received' (duration: 763.95193ms)","trace[764681973] 'applied index is now lower than readState.Index' (duration: 2.47659ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:40.298475Z","caller":"traceutil/trace.go:171","msg":"trace[513960226] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"807.445982ms","start":"2026-08-11T11:07:39.491012Z","end":"2026-08-11T11:07:40.298458Z","steps":["trace[513960226] 'process raft request' (duration: 805.585495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.298707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.412807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:40.298756Z","caller":"traceutil/trace.go:171","msg":"trace[281163308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"355.507858ms","start":"2026-08-11T11:07:39.943235Z","end":"2026-08-11T11:07:40.298743Z","steps":["trace[281163308] 'agreement among raft nodes before linearized reading' (duration: 355.430876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.298787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:39.94322Z","time spent":"355.5605ms","remote":"127.0.0.1:57808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:07:40.298743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:39.490986Z","time spent":"807.525134ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:40.298808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.985993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ct6jf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-11T11:07:40.298851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.8163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T11:07:40.29892Z","caller":"traceutil/trace.go:171","msg":"trace[1981939649] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ct6jf; range_end:; response_count:1; response_revision:625; }","duration":"300.166467ms","start":"2026-08-11T11:07:39.998725Z","end":"2026-08-11T11:07:40.298891Z","steps":["trace[1981939649] 'agreement among raft nodes before linearized reading' (duration: 299.927792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.298996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:39.9987Z","time spent":"300.282509ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-ct6jf\" "} {"level":"info","ts":"2026-08-11T11:07:40.299019Z","caller":"traceutil/trace.go:171","msg":"trace[113468472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"177.036634ms","start":"2026-08-11T11:07:40.121931Z","end":"2026-08-11T11:07:40.298967Z","steps":["trace[113468472] 'agreement among raft nodes before linearized reading' (duration: 176.651087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.298906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.060212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-11T11:07:40.299154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.00089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:07:40.299167Z","caller":"traceutil/trace.go:171","msg":"trace[1068529155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"767.306807ms","start":"2026-08-11T11:07:39.531786Z","end":"2026-08-11T11:07:40.299092Z","steps":["trace[1068529155] 'agreement among raft nodes before linearized reading' (duration: 766.96788ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:40.299261Z","caller":"traceutil/trace.go:171","msg":"trace[144923312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"766.089762ms","start":"2026-08-11T11:07:39.533117Z","end":"2026-08-11T11:07:40.299207Z","steps":["trace[144923312] 'agreement among raft nodes before linearized reading' (duration: 765.439269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.299292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:39.531767Z","time spent":"767.48629ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:07:40.299295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:39.533106Z","time spent":"766.181534ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T11:07:40.670852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.439636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:40.671413Z","caller":"traceutil/trace.go:171","msg":"trace[1333433248] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"368.162222ms","start":"2026-08-11T11:07:40.30321Z","end":"2026-08-11T11:07:40.671373Z","steps":["trace[1333433248] 'process raft request' (duration: 20.110454ms)","trace[1333433248] 'compare' (duration: 347.327284ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:40.67144Z","caller":"traceutil/trace.go:171","msg":"trace[582433911] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"368.04638ms","start":"2026-08-11T11:07:40.303373Z","end":"2026-08-11T11:07:40.671419Z","steps":["trace[582433911] 'process raft request' (duration: 367.952638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.671602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:40.303185Z","time spent":"368.314626ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:40.671615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:40.303362Z","time spent":"368.213673ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:07:40.672079Z","caller":"traceutil/trace.go:171","msg":"trace[1006356811] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"368.54546ms","start":"2026-08-11T11:07:40.303456Z","end":"2026-08-11T11:07:40.672001Z","steps":["trace[1006356811] 'process raft request' (duration: 367.928837ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:40.67214Z","caller":"traceutil/trace.go:171","msg":"trace[481042932] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"358.762204ms","start":"2026-08-11T11:07:40.313355Z","end":"2026-08-11T11:07:40.672117Z","steps":["trace[481042932] 'process raft request' (duration: 358.58737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.672399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:40.30345Z","time spent":"368.820875ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:40.672501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:40.313337Z","time spent":"359.045669ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:07:40.672522Z","caller":"traceutil/trace.go:171","msg":"trace[694664296] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"311.24843ms","start":"2026-08-11T11:07:40.36123Z","end":"2026-08-11T11:07:40.672479Z","steps":["trace[694664296] 'process raft request' (duration: 310.827332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:40.6727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:40.361206Z","time spent":"311.441593ms","remote":"127.0.0.1:57982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:07:40.672986Z","caller":"traceutil/trace.go:171","msg":"trace[617793958] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"266.856608ms","start":"2026-08-11T11:07:40.406096Z","end":"2026-08-11T11:07:40.672952Z","steps":["trace[617793958] 'process raft request' (duration: 266.316678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.543662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.410948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:15.543818Z","caller":"traceutil/trace.go:171","msg":"trace[105864516] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"339.378279ms","start":"2026-08-11T11:09:15.204406Z","end":"2026-08-11T11:09:15.543784Z","steps":["trace[105864516] 'read index received' (duration: 55.743326ms)","trace[105864516] 'applied index is now lower than readState.Index' (duration: 283.633023ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:15.54384Z","caller":"traceutil/trace.go:171","msg":"trace[2111169] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"342.681735ms","start":"2026-08-11T11:09:15.201138Z","end":"2026-08-11T11:09:15.54382Z","steps":["trace[2111169] 'process raft request' (duration: 58.990542ms)","trace[2111169] 'compare' (duration: 283.196114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:15.543921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.201103Z","time spent":"342.781077ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:09:15.544031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.629875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T11:09:15.544073Z","caller":"traceutil/trace.go:171","msg":"trace[211480528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1892; }","duration":"339.694196ms","start":"2026-08-11T11:09:15.204368Z","end":"2026-08-11T11:09:15.544062Z","steps":["trace[211480528] 'agreement among raft nodes before linearized reading' (duration: 339.534122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.544106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.204346Z","time spent":"339.752998ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T11:09:15.585147Z","caller":"traceutil/trace.go:171","msg":"trace[409264243] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"327.44862ms","start":"2026-08-11T11:09:15.257669Z","end":"2026-08-11T11:09:15.585118Z","steps":["trace[409264243] 'process raft request' (duration: 327.249016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.585333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.332851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5268a646-e581-4b3b-a444-778263049523\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-11T11:09:15.585341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.257629Z","time spent":"327.606523ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:09:15.585416Z","caller":"traceutil/trace.go:171","msg":"trace[1629453213] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5268a646-e581-4b3b-a444-778263049523; range_end:; response_count:1; response_revision:1893; }","duration":"124.494024ms","start":"2026-08-11T11:09:15.460898Z","end":"2026-08-11T11:09:15.585392Z","steps":["trace[1629453213] 'agreement among raft nodes before linearized reading' (duration: 124.258319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.585515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.718524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T11:09:15.585638Z","caller":"traceutil/trace.go:171","msg":"trace[471098127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"293.956509ms","start":"2026-08-11T11:09:15.291653Z","end":"2026-08-11T11:09:15.58561Z","steps":["trace[471098127] 'agreement among raft nodes before linearized reading' (duration: 293.599462ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:21.678452Z","caller":"traceutil/trace.go:171","msg":"trace[921371086] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"107.000754ms","start":"2026-08-11T11:09:21.571424Z","end":"2026-08-11T11:09:21.678424Z","steps":["trace[921371086] 'process raft request' (duration: 106.81174ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:10:05.630434Z","caller":"traceutil/trace.go:171","msg":"trace[832256184] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"140.205821ms","start":"2026-08-11T11:10:05.490204Z","end":"2026-08-11T11:10:05.63041Z","steps":["trace[832256184] 'process raft request' (duration: 140.057669ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:10:05.644254Z","caller":"traceutil/trace.go:171","msg":"trace[627867706] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"145.304781ms","start":"2026-08-11T11:10:05.498903Z","end":"2026-08-11T11:10:05.644208Z","steps":["trace[627867706] 'process raft request' (duration: 145.121548ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:10:06.107083Z","caller":"traceutil/trace.go:171","msg":"trace[42615991] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"120.456808ms","start":"2026-08-11T11:10:05.986602Z","end":"2026-08-11T11:10:06.107059Z","steps":["trace[42615991] 'process raft request' (duration: 120.286764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:10:06.14029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.726771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T11:10:06.140444Z","caller":"traceutil/trace.go:171","msg":"trace[149775284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2220; }","duration":"103.912615ms","start":"2026-08-11T11:10:06.036512Z","end":"2026-08-11T11:10:06.140425Z","steps":["trace[149775284] 'agreement among raft nodes before linearized reading' (duration: 103.6355ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:11:02.298026Z","caller":"traceutil/trace.go:171","msg":"trace[893429428] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"132.045585ms","start":"2026-08-11T11:11:02.165955Z","end":"2026-08-11T11:11:02.298001Z","steps":["trace[893429428] 'process raft request' (duration: 131.863311ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:11:02.331087Z","caller":"traceutil/trace.go:171","msg":"trace[1671756572] linearizableReadLoop","detail":"{readStateIndex:2793; appliedIndex:2791; }","duration":"118.918469ms","start":"2026-08-11T11:11:02.212142Z","end":"2026-08-11T11:11:02.33106Z","steps":["trace[1671756572] 'read index received' (duration: 85.751305ms)","trace[1671756572] 'applied index is now lower than readState.Index' (duration: 33.165844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:11:02.331262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.078953ms","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:5"} {"level":"info","ts":"2026-08-11T11:11:02.331243Z","caller":"traceutil/trace.go:171","msg":"trace[1845348066] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"164.079916ms","start":"2026-08-11T11:11:02.167119Z","end":"2026-08-11T11:11:02.331199Z","steps":["trace[1845348066] 'process raft request' (duration: 163.803971ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:11:02.331314Z","caller":"traceutil/trace.go:171","msg":"trace[785389942] 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:2689; }","duration":"119.201144ms","start":"2026-08-11T11:11:02.212098Z","end":"2026-08-11T11:11:02.331299Z","steps":["trace[785389942] 'agreement among raft nodes before linearized reading' (duration: 119.093612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:11:35.957674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.233868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:11:35.957796Z","caller":"traceutil/trace.go:171","msg":"trace[1784048867] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"217.630303ms","start":"2026-08-11T11:11:35.740146Z","end":"2026-08-11T11:11:35.957776Z","steps":["trace[1784048867] 'process raft request' (duration: 58.204541ms)","trace[1784048867] 'compare' (duration: 159.127416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:45.157963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.311381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:45.158085Z","caller":"traceutil/trace.go:171","msg":"trace[1746386885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5129; }","duration":"213.482864ms","start":"2026-08-11T11:15:44.944576Z","end":"2026-08-11T11:15:45.158058Z","steps":["trace[1746386885] 'range keys from in-memory index tree' (duration: 213.224919ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.496379Z","caller":"traceutil/trace.go:171","msg":"trace[239726407] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"136.007442ms","start":"2026-08-11T11:15:45.36034Z","end":"2026-08-11T11:15:45.496348Z","steps":["trace[239726407] 'process raft request' (duration: 135.946711ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.496826Z","caller":"traceutil/trace.go:171","msg":"trace[1297694434] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"137.156655ms","start":"2026-08-11T11:15:45.359218Z","end":"2026-08-11T11:15:45.496375Z","steps":["trace[1297694434] 'process raft request' (duration: 136.939851ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.575977Z","caller":"traceutil/trace.go:171","msg":"trace[1936815960] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"159.501548ms","start":"2026-08-11T11:15:45.416452Z","end":"2026-08-11T11:15:45.575954Z","steps":["trace[1936815960] 'process raft request' (duration: 159.441347ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.576082Z","caller":"traceutil/trace.go:171","msg":"trace[1959447018] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"159.665021ms","start":"2026-08-11T11:15:45.416345Z","end":"2026-08-11T11:15:45.57601Z","steps":["trace[1959447018] 'process raft request' (duration: 152.368472ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.576187Z","caller":"traceutil/trace.go:171","msg":"trace[1138507938] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"155.641836ms","start":"2026-08-11T11:15:45.420505Z","end":"2026-08-11T11:15:45.576147Z","steps":["trace[1138507938] 'process raft request' (duration: 155.417431ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.637412Z","caller":"traceutil/trace.go:171","msg":"trace[752444694] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"133.105299ms","start":"2026-08-11T11:15:45.504269Z","end":"2026-08-11T11:15:45.637374Z","steps":["trace[752444694] 'process raft request' (duration: 132.832873ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:45.637452Z","caller":"traceutil/trace.go:171","msg":"trace[190663939] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"116.984051ms","start":"2026-08-11T11:15:45.520442Z","end":"2026-08-11T11:15:45.637426Z","steps":["trace[190663939] 'process raft request' (duration: 116.826299ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:55.712202Z","caller":"traceutil/trace.go:171","msg":"trace[126256605] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"101.365744ms","start":"2026-08-11T11:15:55.610788Z","end":"2026-08-11T11:15:55.712154Z","steps":["trace[126256605] 'process raft request' (duration: 101.060569ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:55.712266Z","caller":"traceutil/trace.go:171","msg":"trace[2096152267] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"100.551519ms","start":"2026-08-11T11:15:55.611664Z","end":"2026-08-11T11:15:55.712216Z","steps":["trace[2096152267] 'process raft request' (duration: 100.389586ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:58.795867Z","caller":"traceutil/trace.go:171","msg":"trace[1922846249] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"133.804471ms","start":"2026-08-11T11:15:58.661981Z","end":"2026-08-11T11:15:58.795785Z","steps":["trace[1922846249] 'process raft request' (duration: 49.074073ms)","trace[1922846249] 'compare' (duration: 84.445813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:59.415214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.391137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:15:59.415527Z","caller":"traceutil/trace.go:171","msg":"trace[869036138] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"411.969592ms","start":"2026-08-11T11:15:59.00352Z","end":"2026-08-11T11:15:59.41549Z","steps":["trace[869036138] 'process raft request' (duration: 84.220539ms)","trace[869036138] 'compare' (duration: 326.978428ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:59.415569Z","caller":"traceutil/trace.go:171","msg":"trace[1271807199] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"411.939761ms","start":"2026-08-11T11:15:59.003587Z","end":"2026-08-11T11:15:59.415526Z","steps":["trace[1271807199] 'process raft request' (duration: 411.782558ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.41567Z","caller":"traceutil/trace.go:171","msg":"trace[404372728] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5633; }","duration":"410.612826ms","start":"2026-08-11T11:15:59.005036Z","end":"2026-08-11T11:15:59.415649Z","steps":["trace[404372728] 'read index received' (duration: 21.868114ms)","trace[404372728] 'applied index is now lower than readState.Index' (duration: 388.743722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:59.415677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.003492Z","time spent":"412.107165ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:15:59.415741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.003571Z","time spent":"412.085394ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:15:59.415794Z","caller":"traceutil/trace.go:171","msg":"trace[566774810] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"412.163356ms","start":"2026-08-11T11:15:59.00362Z","end":"2026-08-11T11:15:59.415783Z","steps":["trace[566774810] 'process raft request' (duration: 411.84956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.415854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.003609Z","time spent":"412.213747ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:15:59.416036Z","caller":"traceutil/trace.go:171","msg":"trace[99301809] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"407.376274ms","start":"2026-08-11T11:15:59.008637Z","end":"2026-08-11T11:15:59.416014Z","steps":["trace[99301809] 'process raft request' (duration: 406.938806ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.416084Z","caller":"traceutil/trace.go:171","msg":"trace[347518460] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"412.294428ms","start":"2026-08-11T11:15:59.003772Z","end":"2026-08-11T11:15:59.416067Z","steps":["trace[347518460] 'process raft request' (duration: 411.771029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.416162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.003761Z","time spent":"412.362869ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:15:59.416251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.205756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-11T11:15:59.416294Z","caller":"traceutil/trace.go:171","msg":"trace[416932525] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"412.522542ms","start":"2026-08-11T11:15:59.003756Z","end":"2026-08-11T11:15:59.416279Z","steps":["trace[416932525] 'process raft request' (duration: 411.751868ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.416303Z","caller":"traceutil/trace.go:171","msg":"trace[557997831] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5434; }","duration":"411.296388ms","start":"2026-08-11T11:15:59.004995Z","end":"2026-08-11T11:15:59.416291Z","steps":["trace[557997831] 'agreement among raft nodes before linearized reading' (duration: 411.077404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.416351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.004985Z","time spent":"411.350609ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-08-11T11:15:59.41636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.003738Z","time spent":"412.591803ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":898,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:15:59.416164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.008626Z","time spent":"407.470616ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:15:59.416553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.805886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-11T11:15:59.416589Z","caller":"traceutil/trace.go:171","msg":"trace[1002520228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5434; }","duration":"148.870977ms","start":"2026-08-11T11:15:59.267711Z","end":"2026-08-11T11:15:59.416582Z","steps":["trace[1002520228] 'agreement among raft nodes before linearized reading' (duration: 148.780154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.416756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.95587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-11T11:15:59.41688Z","caller":"traceutil/trace.go:171","msg":"trace[1095838235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5434; }","duration":"197.056342ms","start":"2026-08-11T11:15:59.219752Z","end":"2026-08-11T11:15:59.416808Z","steps":["trace[1095838235] 'agreement among raft nodes before linearized reading' (duration: 196.899349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.417451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.522049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-08-11T11:15:59.417531Z","caller":"traceutil/trace.go:171","msg":"trace[2015194099] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5434; }","duration":"278.643341ms","start":"2026-08-11T11:15:59.138864Z","end":"2026-08-11T11:15:59.417507Z","steps":["trace[2015194099] 'agreement among raft nodes before linearized reading' (duration: 277.260174ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.77132Z","caller":"traceutil/trace.go:171","msg":"trace[1346804735] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"347.691372ms","start":"2026-08-11T11:15:59.423575Z","end":"2026-08-11T11:15:59.771267Z","steps":["trace[1346804735] 'process raft request' (duration: 347.364666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.771728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.423553Z","time spent":"347.854955ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:59.775543Z","caller":"traceutil/trace.go:171","msg":"trace[1832341514] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"351.904092ms","start":"2026-08-11T11:15:59.423623Z","end":"2026-08-11T11:15:59.775527Z","steps":["trace[1832341514] 'process raft request' (duration: 351.688328ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.775568Z","caller":"traceutil/trace.go:171","msg":"trace[1062327614] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"348.456937ms","start":"2026-08-11T11:15:59.427093Z","end":"2026-08-11T11:15:59.77555Z","steps":["trace[1062327614] 'process raft request' (duration: 348.347815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.775616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.423603Z","time spent":"351.977553ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:15:59.775643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.427067Z","time spent":"348.540479ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:15:59.775643Z","caller":"traceutil/trace.go:171","msg":"trace[1528730493] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"347.298575ms","start":"2026-08-11T11:15:59.428306Z","end":"2026-08-11T11:15:59.775605Z","steps":["trace[1528730493] 'process raft request' (duration: 347.162772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.775762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.428292Z","time spent":"347.404676ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6975,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:15:59.775962Z","caller":"traceutil/trace.go:171","msg":"trace[1561389619] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5640; }","duration":"352.214858ms","start":"2026-08-11T11:15:59.423733Z","end":"2026-08-11T11:15:59.775948Z","steps":["trace[1561389619] 'read index received' (duration: 347.279534ms)","trace[1561389619] 'applied index is now lower than readState.Index' (duration: 4.933754ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:59.776144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.714936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:15:59.776183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.447372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-11T11:15:59.776217Z","caller":"traceutil/trace.go:171","msg":"trace[589730431] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5441; }","duration":"352.515943ms","start":"2026-08-11T11:15:59.423693Z","end":"2026-08-11T11:15:59.776209Z","steps":["trace[589730431] 'agreement among raft nodes before linearized reading' (duration: 352.35313ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:59.776213Z","caller":"traceutil/trace.go:171","msg":"trace[1339857419] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5441; }","duration":"204.846629ms","start":"2026-08-11T11:15:59.571353Z","end":"2026-08-11T11:15:59.7762Z","steps":["trace[1339857419] 'agreement among raft nodes before linearized reading' (duration: 204.733107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.776239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.685358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-08-11T11:15:59.776246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.423668Z","time spent":"352.571635ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-11T11:15:59.776275Z","caller":"traceutil/trace.go:171","msg":"trace[21074226] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5441; }","duration":"321.750729ms","start":"2026-08-11T11:15:59.454513Z","end":"2026-08-11T11:15:59.776264Z","steps":["trace[21074226] 'agreement among raft nodes before linearized reading' (duration: 321.660257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.776297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.454494Z","time spent":"321.797811ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-08-11T11:15:59.776257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.072741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-11T11:15:59.776346Z","caller":"traceutil/trace.go:171","msg":"trace[1133398531] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5441; }","duration":"345.201374ms","start":"2026-08-11T11:15:59.431126Z","end":"2026-08-11T11:15:59.776328Z","steps":["trace[1133398531] 'agreement among raft nodes before linearized reading' (duration: 344.94979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:15:59.776388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:15:59.43111Z","time spent":"345.269475ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-08-11T11:16:07.194807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.66649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:16:07.194926Z","caller":"traceutil/trace.go:171","msg":"trace[1007426092] linearizableReadLoop","detail":"{readStateIndex:5818; appliedIndex:5817; }","duration":"183.890081ms","start":"2026-08-11T11:16:07.011018Z","end":"2026-08-11T11:16:07.194908Z","steps":["trace[1007426092] 'read index received' (duration: 82.033918ms)","trace[1007426092] 'applied index is now lower than readState.Index' (duration: 101.854933ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:07.19497Z","caller":"traceutil/trace.go:171","msg":"trace[1335264043] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"201.431025ms","start":"2026-08-11T11:16:06.993498Z","end":"2026-08-11T11:16:07.194929Z","steps":["trace[1335264043] 'process raft request' (duration: 99.551881ms)","trace[1335264043] 'compare' (duration: 101.535447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:07.195096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.064944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3cd6964-b662-43fe-9e29-6ab716d24014\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-08-11T11:16:07.195129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.898161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-11T11:16:07.195162Z","caller":"traceutil/trace.go:171","msg":"trace[854213402] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5610; }","duration":"183.939312ms","start":"2026-08-11T11:16:07.01121Z","end":"2026-08-11T11:16:07.195149Z","steps":["trace[854213402] 'agreement among raft nodes before linearized reading' (duration: 183.795549ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:07.195186Z","caller":"traceutil/trace.go:171","msg":"trace[558948982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3cd6964-b662-43fe-9e29-6ab716d24014; range_end:; response_count:1; response_revision:5610; }","duration":"184.142517ms","start":"2026-08-11T11:16:07.011005Z","end":"2026-08-11T11:16:07.195148Z","steps":["trace[558948982] 'agreement among raft nodes before linearized reading' (duration: 183.999434ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:18.120572Z","caller":"traceutil/trace.go:171","msg":"trace[152903255] linearizableReadLoop","detail":"{readStateIndex:5934; appliedIndex:5931; }","duration":"236.859807ms","start":"2026-08-11T11:16:17.883688Z","end":"2026-08-11T11:16:18.120548Z","steps":["trace[152903255] 'read index received' (duration: 59.231705ms)","trace[152903255] 'applied index is now lower than readState.Index' (duration: 177.626832ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:18.12068Z","caller":"traceutil/trace.go:171","msg":"trace[1464650148] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"259.795753ms","start":"2026-08-11T11:16:17.860835Z","end":"2026-08-11T11:16:18.120631Z","steps":["trace[1464650148] 'process raft request' (duration: 235.203766ms)","trace[1464650148] 'compare' (duration: 24.303221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:18.120863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.991165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:16:18.120934Z","caller":"traceutil/trace.go:171","msg":"trace[1252865530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5723; }","duration":"222.110217ms","start":"2026-08-11T11:16:17.898812Z","end":"2026-08-11T11:16:18.120922Z","steps":["trace[1252865530] 'agreement among raft nodes before linearized reading' (duration: 221.899583ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:18.120865Z","caller":"traceutil/trace.go:171","msg":"trace[1802838868] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"259.13562ms","start":"2026-08-11T11:16:17.861686Z","end":"2026-08-11T11:16:18.120822Z","steps":["trace[1802838868] 'process raft request' (duration: 258.805954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:18.121116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.332236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-11T11:16:18.121303Z","caller":"traceutil/trace.go:171","msg":"trace[1186772052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5723; }","duration":"237.592052ms","start":"2026-08-11T11:16:17.88364Z","end":"2026-08-11T11:16:18.121232Z","steps":["trace[1186772052] 'agreement among raft nodes before linearized reading' (duration: 237.108662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:18.121312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.653194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:16:18.121439Z","caller":"traceutil/trace.go:171","msg":"trace[663387788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5723; }","duration":"177.826557ms","start":"2026-08-11T11:16:17.943589Z","end":"2026-08-11T11:16:18.121416Z","steps":["trace[663387788] 'agreement among raft nodes before linearized reading' (duration: 177.650104ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:55.038592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-08-11T11:16:55.122116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"81.462565ms","hash":959871847,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":33853440,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T11:16:55.122908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":959871847,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-08-11T11:17:10.62653Z","caller":"traceutil/trace.go:171","msg":"trace[740747366] transaction","detail":"{read_only:false; response_revision:6166; number_of_response:1; }","duration":"125.808914ms","start":"2026-08-11T11:17:10.50069Z","end":"2026-08-11T11:17:10.626499Z","steps":["trace[740747366] 'process raft request' (duration: 125.141701ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:21:55.0469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6045} {"level":"info","ts":"2026-08-11T11:21:55.141095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6045,"took":"92.073219ms","hash":4172590954,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":21852160,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-11T11:21:55.141184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4172590954,"revision":6045,"compact-revision":3000} {"level":"info","ts":"2026-08-11T11:22:30.519254Z","caller":"traceutil/trace.go:171","msg":"trace[342582652] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"187.400532ms","start":"2026-08-11T11:22:30.331794Z","end":"2026-08-11T11:22:30.519194Z","steps":["trace[342582652] 'process raft request' (duration: 180.294546ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:22:30.52504Z","caller":"traceutil/trace.go:171","msg":"trace[2028939385] linearizableReadLoop","detail":"{readStateIndex:8789; appliedIndex:8787; }","duration":"174.822038ms","start":"2026-08-11T11:22:30.350185Z","end":"2026-08-11T11:22:30.525007Z","steps":["trace[2028939385] 'read index received' (duration: 161.915089ms)","trace[2028939385] 'applied index is now lower than readState.Index' (duration: 12.905558ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:22:30.525144Z","caller":"traceutil/trace.go:171","msg":"trace[946567487] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"193.256565ms","start":"2026-08-11T11:22:30.331847Z","end":"2026-08-11T11:22:30.525103Z","steps":["trace[946567487] 'process raft request' (duration: 192.936569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:22:30.525285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.072363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:22:30.525336Z","caller":"traceutil/trace.go:171","msg":"trace[1867607159] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8487; }","duration":"175.167395ms","start":"2026-08-11T11:22:30.350156Z","end":"2026-08-11T11:22:30.525323Z","steps":["trace[1867607159] 'agreement among raft nodes before linearized reading' (duration: 175.044282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:22:30.525481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.823136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T11:22:30.525515Z","caller":"traceutil/trace.go:171","msg":"trace[2076526934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8487; }","duration":"147.927358ms","start":"2026-08-11T11:22:30.377579Z","end":"2026-08-11T11:22:30.525507Z","steps":["trace[2076526934] 'agreement among raft nodes before linearized reading' (duration: 147.765335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:22:30.525603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.416783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:22:30.525678Z","caller":"traceutil/trace.go:171","msg":"trace[396595922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8487; }","duration":"140.530106ms","start":"2026-08-11T11:22:30.385131Z","end":"2026-08-11T11:22:30.525661Z","steps":["trace[396595922] 'agreement among raft nodes before linearized reading' (duration: 140.342192ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:22:41.918444Z","caller":"traceutil/trace.go:171","msg":"trace[876808937] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"119.447648ms","start":"2026-08-11T11:22:41.798955Z","end":"2026-08-11T11:22:41.918402Z","steps":["trace[876808937] 'process raft request' (duration: 73.750934ms)","trace[876808937] 'compare' (duration: 45.52013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:22:42.961552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.03491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-08-11T11:22:42.961678Z","caller":"traceutil/trace.go:171","msg":"trace[1023549546] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8551; }","duration":"254.224103ms","start":"2026-08-11T11:22:42.707429Z","end":"2026-08-11T11:22:42.961654Z","steps":["trace[1023549546] 'range keys from in-memory index tree' (duration: 253.650551ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:22:43.674956Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002299.wal"} {"level":"info","ts":"2026-08-11T11:22:43.675733Z","caller":"traceutil/trace.go:171","msg":"trace[130311007] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"263.610466ms","start":"2026-08-11T11:22:43.412092Z","end":"2026-08-11T11:22:43.675703Z","steps":["trace[130311007] 'read index received' (duration: 263.105365ms)","trace[130311007] 'applied index is now lower than readState.Index' (duration: 503.911µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:22:43.675879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.766439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:22:43.675839Z","caller":"traceutil/trace.go:171","msg":"trace[45207748] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"319.371372ms","start":"2026-08-11T11:22:43.356429Z","end":"2026-08-11T11:22:43.6758Z","steps":["trace[45207748] 'process raft request' (duration: 318.892412ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:22:43.675915Z","caller":"traceutil/trace.go:171","msg":"trace[1010531950] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8552; }","duration":"263.869701ms","start":"2026-08-11T11:22:43.412034Z","end":"2026-08-11T11:22:43.675904Z","steps":["trace[1010531950] 'agreement among raft nodes before linearized reading' (duration: 263.789449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:22:43.676077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:22:43.35641Z","time spent":"319.535796ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414454,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:22:43.698685Z","caller":"traceutil/trace.go:171","msg":"trace[1098781405] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"138.249622ms","start":"2026-08-11T11:22:43.560409Z","end":"2026-08-11T11:22:43.698659Z","steps":["trace[1098781405] 'process raft request' (duration: 137.770943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:10.72313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.297529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-11T11:23:10.723508Z","caller":"traceutil/trace.go:171","msg":"trace[1277815762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8754; }","duration":"144.734427ms","start":"2026-08-11T11:23:10.578742Z","end":"2026-08-11T11:23:10.723476Z","steps":["trace[1277815762] 'range keys from in-memory index tree' (duration: 144.078884ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:10.877341Z","caller":"traceutil/trace.go:171","msg":"trace[731888450] linearizableReadLoop","detail":"{readStateIndex:9067; appliedIndex:9066; }","duration":"119.896377ms","start":"2026-08-11T11:23:10.757418Z","end":"2026-08-11T11:23:10.877315Z","steps":["trace[731888450] 'read index received' (duration: 119.785945ms)","trace[731888450] 'applied index is now lower than readState.Index' (duration: 109.662µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:23:10.877661Z","caller":"traceutil/trace.go:171","msg":"trace[286574650] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"148.972049ms","start":"2026-08-11T11:23:10.72868Z","end":"2026-08-11T11:23:10.877652Z","steps":["trace[286574650] 'process raft request' (duration: 148.441389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:10.877922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.483998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:23:10.877956Z","caller":"traceutil/trace.go:171","msg":"trace[625598687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8755; }","duration":"120.56013ms","start":"2026-08-11T11:23:10.757388Z","end":"2026-08-11T11:23:10.877948Z","steps":["trace[625598687] 'agreement among raft nodes before linearized reading' (duration: 120.426588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:10.878445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.907447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:23:10.878553Z","caller":"traceutil/trace.go:171","msg":"trace[2053581299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8755; }","duration":"121.08615ms","start":"2026-08-11T11:23:10.757441Z","end":"2026-08-11T11:23:10.878528Z","steps":["trace[2053581299] 'agreement among raft nodes before linearized reading' (duration: 120.767724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:10.878795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.097604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T11:23:10.87882Z","caller":"traceutil/trace.go:171","msg":"trace[866507932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8755; }","duration":"115.147155ms","start":"2026-08-11T11:23:10.763667Z","end":"2026-08-11T11:23:10.878814Z","steps":["trace[866507932] 'agreement among raft nodes before linearized reading' (duration: 115.078213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:10.878982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.314914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:23:10.879009Z","caller":"traceutil/trace.go:171","msg":"trace[5077145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8755; }","duration":"121.363265ms","start":"2026-08-11T11:23:10.757636Z","end":"2026-08-11T11:23:10.878999Z","steps":["trace[5077145] 'agreement among raft nodes before linearized reading' (duration: 121.299514ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:25.047294Z","caller":"traceutil/trace.go:171","msg":"trace[1542409446] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"116.546063ms","start":"2026-08-11T11:23:24.930712Z","end":"2026-08-11T11:23:25.047258Z","steps":["trace[1542409446] 'process raft request' (duration: 116.357679ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:25.093711Z","caller":"traceutil/trace.go:171","msg":"trace[1494489963] linearizableReadLoop","detail":"{readStateIndex:9190; appliedIndex:9189; }","duration":"150.782503ms","start":"2026-08-11T11:23:24.942894Z","end":"2026-08-11T11:23:25.093677Z","steps":["trace[1494489963] 'read index received' (duration: 104.357767ms)","trace[1494489963] 'applied index is now lower than readState.Index' (duration: 46.422356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:23:25.094086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.169911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:23:25.094195Z","caller":"traceutil/trace.go:171","msg":"trace[547559090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8870; }","duration":"151.352064ms","start":"2026-08-11T11:23:24.94282Z","end":"2026-08-11T11:23:25.094172Z","steps":["trace[547559090] 'agreement among raft nodes before linearized reading' (duration: 151.13326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:25.094224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.356766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T11:23:25.094341Z","caller":"traceutil/trace.go:171","msg":"trace[664718851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8870; }","duration":"120.559709ms","start":"2026-08-11T11:23:24.973766Z","end":"2026-08-11T11:23:25.094326Z","steps":["trace[664718851] 'agreement among raft nodes before linearized reading' (duration: 120.217903ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:25.094194Z","caller":"traceutil/trace.go:171","msg":"trace[561112737] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"157.033884ms","start":"2026-08-11T11:23:24.93712Z","end":"2026-08-11T11:23:25.094154Z","steps":["trace[561112737] 'process raft request' (duration: 156.34062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:25.09471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.541781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:23:25.09481Z","caller":"traceutil/trace.go:171","msg":"trace[1245722406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8870; }","duration":"104.676244ms","start":"2026-08-11T11:23:24.990109Z","end":"2026-08-11T11:23:25.094786Z","steps":["trace[1245722406] 'agreement among raft nodes before linearized reading' (duration: 104.430639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:23:25.24394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.898959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:23:25.244331Z","caller":"traceutil/trace.go:171","msg":"trace[1895116409] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"143.89916ms","start":"2026-08-11T11:23:25.10041Z","end":"2026-08-11T11:23:25.24431Z","steps":["trace[1895116409] 'process raft request' (duration: 143.834879ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:25.244596Z","caller":"traceutil/trace.go:171","msg":"trace[1577548695] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"144.980101ms","start":"2026-08-11T11:23:25.099604Z","end":"2026-08-11T11:23:25.244584Z","steps":["trace[1577548695] 'process raft request' (duration: 41.324277ms)","trace[1577548695] 'compare' (duration: 102.658595ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:23:25.244759Z","caller":"traceutil/trace.go:171","msg":"trace[862686163] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"144.37948ms","start":"2026-08-11T11:23:25.100369Z","end":"2026-08-11T11:23:25.244748Z","steps":["trace[862686163] 'process raft request' (duration: 143.840969ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:23:25.244752Z","caller":"traceutil/trace.go:171","msg":"trace[1717821427] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"144.565573ms","start":"2026-08-11T11:23:25.100138Z","end":"2026-08-11T11:23:25.244703Z","steps":["trace[1717821427] 'process raft request' (duration: 143.963461ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:24:48.280421Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T11:24:48.287334Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-11T11:24:48.287734Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-11T11:26:55.054685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8122} {"level":"info","ts":"2026-08-11T11:26:55.120207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8122,"took":"62.638872ms","hash":3874097659,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":28438528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-11T11:26:55.120301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3874097659,"revision":8122,"compact-revision":6045} {"level":"info","ts":"2026-08-11T11:30:03.82114Z","caller":"traceutil/trace.go:171","msg":"trace[1676218882] transaction","detail":"{read_only:false; response_revision:11957; number_of_response:1; }","duration":"216.955512ms","start":"2026-08-11T11:30:03.604149Z","end":"2026-08-11T11:30:03.821105Z","steps":["trace[1676218882] 'process raft request' (duration: 216.749018ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:03.859038Z","caller":"traceutil/trace.go:171","msg":"trace[1436579076] transaction","detail":"{read_only:false; response_revision:11959; number_of_response:1; }","duration":"250.822868ms","start":"2026-08-11T11:30:03.608173Z","end":"2026-08-11T11:30:03.858995Z","steps":["trace[1436579076] 'process raft request' (duration: 250.643274ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:03.859046Z","caller":"traceutil/trace.go:171","msg":"trace[1266721088] transaction","detail":"{read_only:false; response_revision:11958; number_of_response:1; }","duration":"251.604993ms","start":"2026-08-11T11:30:03.607407Z","end":"2026-08-11T11:30:03.859012Z","steps":["trace[1266721088] 'process raft request' (duration: 251.269797ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:03.859144Z","caller":"traceutil/trace.go:171","msg":"trace[887326490] transaction","detail":"{read_only:false; response_revision:11960; number_of_response:1; }","duration":"115.231012ms","start":"2026-08-11T11:30:03.743863Z","end":"2026-08-11T11:30:03.859094Z","steps":["trace[887326490] 'process raft request' (duration: 115.001847ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:03.861631Z","caller":"traceutil/trace.go:171","msg":"trace[796001359] linearizableReadLoop","detail":"{readStateIndex:12373; appliedIndex:12370; }","duration":"208.367135ms","start":"2026-08-11T11:30:03.65323Z","end":"2026-08-11T11:30:03.861597Z","steps":["trace[796001359] 'read index received' (duration: 167.678388ms)","trace[796001359] 'applied index is now lower than readState.Index' (duration: 40.684707ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:30:03.86191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.65239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-11T11:30:03.86196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.204953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:30:03.861995Z","caller":"traceutil/trace.go:171","msg":"trace[910554367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11960; }","duration":"208.797803ms","start":"2026-08-11T11:30:03.653175Z","end":"2026-08-11T11:30:03.861973Z","steps":["trace[910554367] 'agreement among raft nodes before linearized reading' (duration: 208.529928ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:03.862008Z","caller":"traceutil/trace.go:171","msg":"trace[129528927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11960; }","duration":"207.317266ms","start":"2026-08-11T11:30:03.654674Z","end":"2026-08-11T11:30:03.861992Z","steps":["trace[129528927] 'agreement among raft nodes before linearized reading' (duration: 207.113862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:30:03.862031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.303499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:30:03.862079Z","caller":"traceutil/trace.go:171","msg":"trace[534572024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11960; }","duration":"200.37288ms","start":"2026-08-11T11:30:03.661695Z","end":"2026-08-11T11:30:03.862067Z","steps":["trace[534572024] 'agreement among raft nodes before linearized reading' (duration: 200.145456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:30:03.862089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.471502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:30:03.862235Z","caller":"traceutil/trace.go:171","msg":"trace[981524377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11960; }","duration":"200.641286ms","start":"2026-08-11T11:30:03.66156Z","end":"2026-08-11T11:30:03.862201Z","steps":["trace[981524377] 'agreement among raft nodes before linearized reading' (duration: 200.32124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:30:26.099333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.535595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:30:26.099763Z","caller":"traceutil/trace.go:171","msg":"trace[500723634] transaction","detail":"{read_only:false; response_revision:12084; number_of_response:1; }","duration":"117.785771ms","start":"2026-08-11T11:30:25.98194Z","end":"2026-08-11T11:30:26.099726Z","steps":["trace[500723634] 'process raft request' (duration: 13.46857ms)","trace[500723634] 'compare' (duration: 103.354352ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:30:26.099879Z","caller":"traceutil/trace.go:171","msg":"trace[556975891] transaction","detail":"{read_only:false; response_revision:12085; number_of_response:1; }","duration":"115.481948ms","start":"2026-08-11T11:30:25.984375Z","end":"2026-08-11T11:30:26.099857Z","steps":["trace[556975891] 'process raft request' (duration: 115.239323ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:26.099954Z","caller":"traceutil/trace.go:171","msg":"trace[676842353] transaction","detail":"{read_only:false; response_revision:12086; number_of_response:1; }","duration":"115.10105ms","start":"2026-08-11T11:30:25.984816Z","end":"2026-08-11T11:30:26.099917Z","steps":["trace[676842353] 'process raft request' (duration: 114.901156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:30:26.100265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.710812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:30:26.100334Z","caller":"traceutil/trace.go:171","msg":"trace[681428648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12086; }","duration":"114.801264ms","start":"2026-08-11T11:30:25.985514Z","end":"2026-08-11T11:30:26.100316Z","steps":["trace[681428648] 'agreement among raft nodes before linearized reading' (duration: 114.60462ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:26.099811Z","caller":"traceutil/trace.go:171","msg":"trace[783507391] linearizableReadLoop","detail":"{readStateIndex:12505; appliedIndex:12502; }","duration":"114.241924ms","start":"2026-08-11T11:30:25.985548Z","end":"2026-08-11T11:30:26.09979Z","steps":["trace[783507391] 'read index received' (duration: 9.881791ms)","trace[783507391] 'applied index is now lower than readState.Index' (duration: 104.358513ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:31:55.061904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10534} {"level":"info","ts":"2026-08-11T11:31:55.147187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10534,"took":"82.227301ms","hash":956072780,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":26341376,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-11T11:31:55.147282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":956072780,"revision":10534,"compact-revision":8122} {"level":"info","ts":"2026-08-11T11:36:55.068814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12663} {"level":"info","ts":"2026-08-11T11:36:55.350305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12663,"took":"278.064051ms","hash":920421799,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":27119616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-11T11:36:55.350399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":920421799,"revision":12663,"compact-revision":10534} {"level":"info","ts":"2026-08-11T11:37:26.67987Z","caller":"traceutil/trace.go:171","msg":"trace[1585311776] transaction","detail":"{read_only:false; response_revision:15051; number_of_response:1; }","duration":"116.926275ms","start":"2026-08-11T11:37:26.562898Z","end":"2026-08-11T11:37:26.679825Z","steps":["trace[1585311776] 'process raft request' (duration: 112.322486ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:37:26.680266Z","caller":"traceutil/trace.go:171","msg":"trace[980136846] transaction","detail":"{read_only:false; response_revision:15052; number_of_response:1; }","duration":"116.365784ms","start":"2026-08-11T11:37:26.563861Z","end":"2026-08-11T11:37:26.680227Z","steps":["trace[980136846] 'process raft request' (duration: 115.867434ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:37:26.680402Z","caller":"traceutil/trace.go:171","msg":"trace[1438500705] transaction","detail":"{read_only:false; response_revision:15053; number_of_response:1; }","duration":"116.446645ms","start":"2026-08-11T11:37:26.563912Z","end":"2026-08-11T11:37:26.680359Z","steps":["trace[1438500705] 'process raft request' (duration: 116.252042ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.202166Z","caller":"traceutil/trace.go:171","msg":"trace[661513871] transaction","detail":"{read_only:false; response_revision:15867; number_of_response:1; }","duration":"148.704907ms","start":"2026-08-11T11:38:54.053436Z","end":"2026-08-11T11:38:54.202141Z","steps":["trace[661513871] 'process raft request' (duration: 148.544614ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.313456Z","caller":"traceutil/trace.go:171","msg":"trace[229825430] transaction","detail":"{read_only:false; response_revision:15868; number_of_response:1; }","duration":"259.376711ms","start":"2026-08-11T11:38:54.054011Z","end":"2026-08-11T11:38:54.313388Z","steps":["trace[229825430] 'process raft request' (duration: 254.785742ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.313694Z","caller":"traceutil/trace.go:171","msg":"trace[1214221979] transaction","detail":"{read_only:false; response_revision:15870; number_of_response:1; }","duration":"182.955098ms","start":"2026-08-11T11:38:54.130727Z","end":"2026-08-11T11:38:54.313682Z","steps":["trace[1214221979] 'process raft request' (duration: 182.842466ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.313743Z","caller":"traceutil/trace.go:171","msg":"trace[1767741721] transaction","detail":"{read_only:false; response_revision:15869; number_of_response:1; }","duration":"259.076805ms","start":"2026-08-11T11:38:54.054612Z","end":"2026-08-11T11:38:54.313688Z","steps":["trace[1767741721] 'process raft request' (duration: 258.880241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:54.313923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.818758ms","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-08-11T11:38:54.313975Z","caller":"traceutil/trace.go:171","msg":"trace[1832896091] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15870; }","duration":"220.9171ms","start":"2026-08-11T11:38:54.093047Z","end":"2026-08-11T11:38:54.313964Z","steps":["trace[1832896091] 'agreement among raft nodes before linearized reading' (duration: 220.825099ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.31362Z","caller":"traceutil/trace.go:171","msg":"trace[1046006646] linearizableReadLoop","detail":"{readStateIndex:16422; appliedIndex:16419; }","duration":"220.516273ms","start":"2026-08-11T11:38:54.093085Z","end":"2026-08-11T11:38:54.313601Z","steps":["trace[1046006646] 'read index received' (duration: 108.871309ms)","trace[1046006646] 'applied index is now lower than readState.Index' (duration: 111.643853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:38:54.314346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.151394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:38:54.314394Z","caller":"traceutil/trace.go:171","msg":"trace[571354099] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15870; }","duration":"220.255886ms","start":"2026-08-11T11:38:54.094129Z","end":"2026-08-11T11:38:54.314385Z","steps":["trace[571354099] 'agreement among raft nodes before linearized reading' (duration: 220.171345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:54.314552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.610838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4dhxk\" ","response":"range_response_count:1 size:20565"} {"level":"warn","ts":"2026-08-11T11:38:54.314598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.194452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-11T11:38:54.31461Z","caller":"traceutil/trace.go:171","msg":"trace[1447396658] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4dhxk; range_end:; response_count:1; response_revision:15870; }","duration":"160.72717ms","start":"2026-08-11T11:38:54.15387Z","end":"2026-08-11T11:38:54.314597Z","steps":["trace[1447396658] 'agreement among raft nodes before linearized reading' (duration: 160.480375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:54.314669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.408201ms","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-08-11T11:38:54.314702Z","caller":"traceutil/trace.go:171","msg":"trace[891383305] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15870; }","duration":"220.478982ms","start":"2026-08-11T11:38:54.094208Z","end":"2026-08-11T11:38:54.314687Z","steps":["trace[891383305] 'agreement among raft nodes before linearized reading' (duration: 220.446861ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:54.314626Z","caller":"traceutil/trace.go:171","msg":"trace[1795560694] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15870; }","duration":"213.234823ms","start":"2026-08-11T11:38:54.101385Z","end":"2026-08-11T11:38:54.31462Z","steps":["trace[1795560694] 'agreement among raft nodes before linearized reading' (duration: 213.128291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:55.369519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.655199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-11T11:38:55.369602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.710309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T11:38:55.369691Z","caller":"traceutil/trace.go:171","msg":"trace[728824306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15872; }","duration":"173.859733ms","start":"2026-08-11T11:38:55.195809Z","end":"2026-08-11T11:38:55.369669Z","steps":["trace[728824306] 'range keys from in-memory index tree' (duration: 173.457935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:55.369676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.074855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:38:55.36963Z","caller":"traceutil/trace.go:171","msg":"trace[34749199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15872; }","duration":"173.799771ms","start":"2026-08-11T11:38:55.19581Z","end":"2026-08-11T11:38:55.36961Z","steps":["trace[34749199] 'range keys from in-memory index tree' (duration: 173.388584ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:55.369763Z","caller":"traceutil/trace.go:171","msg":"trace[1981436678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15872; }","duration":"175.164777ms","start":"2026-08-11T11:38:55.194579Z","end":"2026-08-11T11:38:55.369744Z","steps":["trace[1981436678] 'range keys from in-memory index tree' (duration: 174.635608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:55.370082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.730359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:38:55.370195Z","caller":"traceutil/trace.go:171","msg":"trace[1689210322] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:15872; }","duration":"105.930612ms","start":"2026-08-11T11:38:55.264241Z","end":"2026-08-11T11:38:55.370172Z","steps":["trace[1689210322] 'count revisions from in-memory index tree' (duration: 105.583395ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:41:55.075835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14680} {"level":"info","ts":"2026-08-11T11:41:55.162455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14680,"took":"82.740608ms","hash":2037565831,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":34054144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T11:41:55.162529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2037565831,"revision":14680,"compact-revision":12663} {"level":"info","ts":"2026-08-11T11:44:50.655753Z","caller":"traceutil/trace.go:171","msg":"trace[108826375] linearizableReadLoop","detail":"{readStateIndex:19136; appliedIndex:19135; }","duration":"163.57981ms","start":"2026-08-11T11:44:50.492141Z","end":"2026-08-11T11:44:50.655721Z","steps":["trace[108826375] 'read index received' (duration: 110.147038ms)","trace[108826375] 'applied index is now lower than readState.Index' (duration: 53.431242ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:44:50.655807Z","caller":"traceutil/trace.go:171","msg":"trace[1994736126] transaction","detail":"{read_only:false; response_revision:18497; number_of_response:1; }","duration":"178.763002ms","start":"2026-08-11T11:44:50.476981Z","end":"2026-08-11T11:44:50.655744Z","steps":["trace[1994736126] 'process raft request' (duration: 125.30309ms)","trace[1994736126] 'compare' (duration: 53.286399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:44:50.655994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.827404ms","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-08-11T11:44:50.656042Z","caller":"traceutil/trace.go:171","msg":"trace[1949902158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18497; }","duration":"163.916316ms","start":"2026-08-11T11:44:50.49211Z","end":"2026-08-11T11:44:50.656026Z","steps":["trace[1949902158] 'agreement among raft nodes before linearized reading' (duration: 163.730882ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:46:13.373271Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T11:46:13.378708Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-11T11:46:13.378805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-11T11:46:55.083449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17333} {"level":"info","ts":"2026-08-11T11:46:55.184917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17333,"took":"96.619016ms","hash":2405806387,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":34865152,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-11T11:46:55.184997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2405806387,"revision":17333,"compact-revision":14680} {"level":"info","ts":"2026-08-11T11:50:46.263647Z","caller":"traceutil/trace.go:171","msg":"trace[696219469] transaction","detail":"{read_only:false; response_revision:21232; number_of_response:1; }","duration":"111.575461ms","start":"2026-08-11T11:50:46.152045Z","end":"2026-08-11T11:50:46.263621Z","steps":["trace[696219469] 'process raft request' (duration: 111.399437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:50:46.963448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.528526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:50:46.963696Z","caller":"traceutil/trace.go:171","msg":"trace[960513157] linearizableReadLoop","detail":"{readStateIndex:21960; appliedIndex:21959; }","duration":"137.465827ms","start":"2026-08-11T11:50:46.826211Z","end":"2026-08-11T11:50:46.963677Z","steps":["trace[960513157] 'read index received' (duration: 8.575294ms)","trace[960513157] 'applied index is now lower than readState.Index' (duration: 128.889003ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:50:46.963834Z","caller":"traceutil/trace.go:171","msg":"trace[919786517] transaction","detail":"{read_only:false; response_revision:21234; number_of_response:1; }","duration":"159.828456ms","start":"2026-08-11T11:50:46.803962Z","end":"2026-08-11T11:50:46.963791Z","steps":["trace[919786517] 'process raft request' (duration: 30.862902ms)","trace[919786517] 'compare' (duration: 128.253301ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:50:46.963868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.664071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T11:50:46.964085Z","caller":"traceutil/trace.go:171","msg":"trace[175731748] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21234; }","duration":"137.929376ms","start":"2026-08-11T11:50:46.82614Z","end":"2026-08-11T11:50:46.96407Z","steps":["trace[175731748] 'agreement among raft nodes before linearized reading' (duration: 137.61667ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:50:47.488597Z","caller":"traceutil/trace.go:171","msg":"trace[265055349] transaction","detail":"{read_only:false; response_revision:21236; number_of_response:1; }","duration":"331.461099ms","start":"2026-08-11T11:50:47.157108Z","end":"2026-08-11T11:50:47.488569Z","steps":["trace[265055349] 'process raft request' (duration: 331.284866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:50:47.488847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:50:47.157077Z","time spent":"331.600892ms","remote":"127.0.0.1:58026","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-08-11T11:50:47.552601Z","caller":"traceutil/trace.go:171","msg":"trace[255775696] transaction","detail":"{read_only:false; response_revision:21237; number_of_response:1; }","duration":"395.431415ms","start":"2026-08-11T11:50:47.157143Z","end":"2026-08-11T11:50:47.552574Z","steps":["trace[255775696] 'process raft request' (duration: 385.882533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:50:47.552943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:50:47.157113Z","time spent":"395.568098ms","remote":"127.0.0.1:58026","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-08-11T11:50:47.552976Z","caller":"traceutil/trace.go:171","msg":"trace[986418878] linearizableReadLoop","detail":"{readStateIndex:21964; appliedIndex:21961; }","duration":"340.93365ms","start":"2026-08-11T11:50:47.212013Z","end":"2026-08-11T11:50:47.552947Z","steps":["trace[986418878] 'read index received' (duration: 276.412712ms)","trace[986418878] 'applied index is now lower than readState.Index' (duration: 64.518098ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:50:47.553287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.249887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:50:47.553378Z","caller":"traceutil/trace.go:171","msg":"trace[623371549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21238; }","duration":"341.398769ms","start":"2026-08-11T11:50:47.211958Z","end":"2026-08-11T11:50:47.553357Z","steps":["trace[623371549] 'agreement among raft nodes before linearized reading' (duration: 341.235196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:50:47.553433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:50:47.211932Z","time spent":"341.492171ms","remote":"127.0.0.1:57806","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-08-11T11:50:47.553802Z","caller":"traceutil/trace.go:171","msg":"trace[1476569441] transaction","detail":"{read_only:false; response_revision:21238; number_of_response:1; }","duration":"396.479766ms","start":"2026-08-11T11:50:47.157307Z","end":"2026-08-11T11:50:47.553786Z","steps":["trace[1476569441] 'process raft request' (duration: 395.221101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:50:47.55392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:50:47.157293Z","time spent":"396.554768ms","remote":"127.0.0.1:58026","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-08-11T11:51:55.088546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19665} {"level":"info","ts":"2026-08-11T11:51:55.206809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19665,"took":"114.581898ms","hash":3121648835,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":29941760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T11:51:55.20687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3121648835,"revision":19665,"compact-revision":17333} {"level":"warn","ts":"2026-08-11T11:54:41.537397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.259136ms","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-08-11T11:54:41.537521Z","caller":"traceutil/trace.go:171","msg":"trace[1926385465] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:23681; }","duration":"109.48822ms","start":"2026-08-11T11:54:41.42801Z","end":"2026-08-11T11:54:41.537498Z","steps":["trace[1926385465] 'count revisions from in-memory index tree' (duration: 109.104103ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:56:55.105902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21864} {"level":"info","ts":"2026-08-11T11:56:55.218952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21864,"took":"105.085396ms","hash":599167143,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":44802048,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-11T11:56:55.21902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":599167143,"revision":21864,"compact-revision":19665} {"level":"info","ts":"2026-08-11T11:58:08.296968Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067e5.wal"} {"level":"info","ts":"2026-08-11T11:58:13.461438Z","caller":"traceutil/trace.go:171","msg":"trace[1865346762] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"127.662787ms","start":"2026-08-11T11:58:13.333737Z","end":"2026-08-11T11:58:13.4614Z","steps":["trace[1865346762] 'process raft request' (duration: 127.127947ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:13.461486Z","caller":"traceutil/trace.go:171","msg":"trace[1326066207] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"126.926153ms","start":"2026-08-11T11:58:13.334528Z","end":"2026-08-11T11:58:13.461454Z","steps":["trace[1326066207] 'process raft request' (duration: 126.79198ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:13.461966Z","caller":"traceutil/trace.go:171","msg":"trace[1914774755] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"116.39235ms","start":"2026-08-11T11:58:13.345526Z","end":"2026-08-11T11:58:13.461918Z","steps":["trace[1914774755] 'process raft request' (duration: 116.155895ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:17.811053Z","caller":"traceutil/trace.go:171","msg":"trace[499051663] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"127.543706ms","start":"2026-08-11T11:58:17.68347Z","end":"2026-08-11T11:58:17.811014Z","steps":["trace[499051663] 'process raft request' (duration: 127.346892ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:18.990702Z","caller":"traceutil/trace.go:171","msg":"trace[1157393952] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"208.885582ms","start":"2026-08-11T11:58:18.781784Z","end":"2026-08-11T11:58:18.99067Z","steps":["trace[1157393952] 'process raft request' (duration: 208.559295ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:19.003783Z","caller":"traceutil/trace.go:171","msg":"trace[395320442] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"213.656214ms","start":"2026-08-11T11:58:18.790101Z","end":"2026-08-11T11:58:19.003757Z","steps":["trace[395320442] 'process raft request' (duration: 213.247245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:19.184201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.349886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T11:58:19.1843Z","caller":"traceutil/trace.go:171","msg":"trace[1237723749] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25860; }","duration":"107.521489ms","start":"2026-08-11T11:58:19.076758Z","end":"2026-08-11T11:58:19.184279Z","steps":["trace[1237723749] 'count revisions from in-memory index tree' (duration: 107.003739ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:19.481055Z","caller":"traceutil/trace.go:171","msg":"trace[178445824] linearizableReadLoop","detail":"{readStateIndex:26718; appliedIndex:26717; }","duration":"141.426062ms","start":"2026-08-11T11:58:19.339597Z","end":"2026-08-11T11:58:19.481023Z","steps":["trace[178445824] 'read index received' (duration: 141.109836ms)","trace[178445824] 'applied index is now lower than readState.Index' (duration: 314.516µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:19.481159Z","caller":"traceutil/trace.go:171","msg":"trace[123419140] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"143.109044ms","start":"2026-08-11T11:58:19.33801Z","end":"2026-08-11T11:58:19.481119Z","steps":["trace[123419140] 'process raft request' (duration: 142.770378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:19.481416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.7627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T11:58:19.481535Z","caller":"traceutil/trace.go:171","msg":"trace[1579195650] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25861; }","duration":"141.934383ms","start":"2026-08-11T11:58:19.339574Z","end":"2026-08-11T11:58:19.481509Z","steps":["trace[1579195650] 'agreement among raft nodes before linearized reading' (duration: 141.568906ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:19.58084Z","caller":"traceutil/trace.go:171","msg":"trace[1814683346] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"209.036454ms","start":"2026-08-11T11:58:19.371778Z","end":"2026-08-11T11:58:19.580814Z","steps":["trace[1814683346] 'process raft request' (duration: 115.91549ms)","trace[1814683346] 'compare' (duration: 92.677254ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:23.670731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.953317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-11T11:58:23.670871Z","caller":"traceutil/trace.go:171","msg":"trace[2001219330] linearizableReadLoop","detail":"{readStateIndex:26748; appliedIndex:26747; }","duration":"170.138865ms","start":"2026-08-11T11:58:23.50071Z","end":"2026-08-11T11:58:23.670849Z","steps":["trace[2001219330] 'read index received' (duration: 20.026095ms)","trace[2001219330] 'applied index is now lower than readState.Index' (duration: 150.11084ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:23.671141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.415291ms","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-08-11T11:58:23.671193Z","caller":"traceutil/trace.go:171","msg":"trace[788863090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25890; }","duration":"170.528392ms","start":"2026-08-11T11:58:23.500651Z","end":"2026-08-11T11:58:23.671179Z","steps":["trace[788863090] 'agreement among raft nodes before linearized reading' (duration: 170.308478ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:23.923026Z","caller":"traceutil/trace.go:171","msg":"trace[1308171772] linearizableReadLoop","detail":"{readStateIndex:26754; appliedIndex:26753; }","duration":"206.969484ms","start":"2026-08-11T11:58:23.716032Z","end":"2026-08-11T11:58:23.923001Z","steps":["trace[1308171772] 'read index received' (duration: 206.736418ms)","trace[1308171772] 'applied index is now lower than readState.Index' (duration: 231.656µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:23.923166Z","caller":"traceutil/trace.go:171","msg":"trace[1535272469] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"239.717195ms","start":"2026-08-11T11:58:23.683408Z","end":"2026-08-11T11:58:23.923126Z","steps":["trace[1535272469] 'process raft request' (duration: 239.412849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:23.923207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.139368ms","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:6"} {"level":"info","ts":"2026-08-11T11:58:23.923337Z","caller":"traceutil/trace.go:171","msg":"trace[1543523452] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25896; }","duration":"207.360232ms","start":"2026-08-11T11:58:23.715962Z","end":"2026-08-11T11:58:23.923323Z","steps":["trace[1543523452] 'agreement among raft nodes before linearized reading' (duration: 207.131068ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:23.95932Z","caller":"traceutil/trace.go:171","msg":"trace[853423190] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"119.055042ms","start":"2026-08-11T11:58:23.840219Z","end":"2026-08-11T11:58:23.959274Z","steps":["trace[853423190] 'process raft request' (duration: 118.847507ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:24.32743Z","caller":"traceutil/trace.go:171","msg":"trace[1438086127] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"201.939097ms","start":"2026-08-11T11:58:24.12546Z","end":"2026-08-11T11:58:24.327399Z","steps":["trace[1438086127] 'process raft request' (duration: 201.887336ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:24.327482Z","caller":"traceutil/trace.go:171","msg":"trace[2036006482] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"203.298544ms","start":"2026-08-11T11:58:24.124141Z","end":"2026-08-11T11:58:24.327439Z","steps":["trace[2036006482] 'process raft request' (duration: 203.029859ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:24.339831Z","caller":"traceutil/trace.go:171","msg":"trace[1715097787] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"109.698801ms","start":"2026-08-11T11:58:24.230098Z","end":"2026-08-11T11:58:24.339797Z","steps":["trace[1715097787] 'process raft request' (duration: 109.491097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:25.374952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.757912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:25.375069Z","caller":"traceutil/trace.go:171","msg":"trace[1170530174] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25904; }","duration":"217.967826ms","start":"2026-08-11T11:58:25.157074Z","end":"2026-08-11T11:58:25.375042Z","steps":["trace[1170530174] 'range keys from in-memory index tree' (duration: 217.596929ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:25.948847Z","caller":"traceutil/trace.go:171","msg":"trace[1099304364] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"263.90402ms","start":"2026-08-11T11:58:25.684914Z","end":"2026-08-11T11:58:25.948818Z","steps":["trace[1099304364] 'process raft request' (duration: 263.748437ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:25.949229Z","caller":"traceutil/trace.go:171","msg":"trace[1735103839] linearizableReadLoop","detail":"{readStateIndex:26764; appliedIndex:26762; }","duration":"263.502802ms","start":"2026-08-11T11:58:25.685694Z","end":"2026-08-11T11:58:25.949196Z","steps":["trace[1735103839] 'read index received' (duration: 90.801058ms)","trace[1735103839] 'applied index is now lower than readState.Index' (duration: 172.700494ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:25.949245Z","caller":"traceutil/trace.go:171","msg":"trace[1543695795] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"263.510672ms","start":"2026-08-11T11:58:25.685681Z","end":"2026-08-11T11:58:25.949192Z","steps":["trace[1543695795] 'process raft request' (duration: 263.095674ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:25.949372Z","caller":"traceutil/trace.go:171","msg":"trace[731697795] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"263.339759ms","start":"2026-08-11T11:58:25.685995Z","end":"2026-08-11T11:58:25.949335Z","steps":["trace[731697795] 'process raft request' (duration: 263.068064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:25.949501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.808998ms","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-08-11T11:58:25.94956Z","caller":"traceutil/trace.go:171","msg":"trace[262731960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25908; }","duration":"263.944771ms","start":"2026-08-11T11:58:25.6856Z","end":"2026-08-11T11:58:25.949545Z","steps":["trace[262731960] 'agreement among raft nodes before linearized reading' (duration: 263.748717ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.160911Z","caller":"traceutil/trace.go:171","msg":"trace[894972833] linearizableReadLoop","detail":"{readStateIndex:26769; appliedIndex:26767; }","duration":"189.466947ms","start":"2026-08-11T11:58:25.9714Z","end":"2026-08-11T11:58:26.160867Z","steps":["trace[894972833] 'read index received' (duration: 134.406867ms)","trace[894972833] 'applied index is now lower than readState.Index' (duration: 55.05899ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:26.16108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.180595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:26.161126Z","caller":"traceutil/trace.go:171","msg":"trace[1815760689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25911; }","duration":"211.285488ms","start":"2026-08-11T11:58:25.949828Z","end":"2026-08-11T11:58:26.161113Z","steps":["trace[1815760689] 'agreement among raft nodes before linearized reading' (duration: 211.173095ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.161176Z","caller":"traceutil/trace.go:171","msg":"trace[825919674] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"200.946689ms","start":"2026-08-11T11:58:25.960149Z","end":"2026-08-11T11:58:26.161096Z","steps":["trace[825919674] 'process raft request' (duration: 145.642114ms)","trace[825919674] 'compare' (duration: 54.519299ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:26.161239Z","caller":"traceutil/trace.go:171","msg":"trace[1305862654] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"195.989573ms","start":"2026-08-11T11:58:25.965119Z","end":"2026-08-11T11:58:26.161108Z","steps":["trace[1305862654] 'process raft request' (duration: 195.643946ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.275065Z","caller":"traceutil/trace.go:171","msg":"trace[588070792] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"299.653078ms","start":"2026-08-11T11:58:25.975389Z","end":"2026-08-11T11:58:26.275042Z","steps":["trace[588070792] 'process raft request' (duration: 299.071017ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.275421Z","caller":"traceutil/trace.go:171","msg":"trace[539468465] linearizableReadLoop","detail":"{readStateIndex:26771; appliedIndex:26769; }","duration":"114.413012ms","start":"2026-08-11T11:58:26.160974Z","end":"2026-08-11T11:58:26.275387Z","steps":["trace[539468465] 'read index received' (duration: 113.504375ms)","trace[539468465] 'applied index is now lower than readState.Index' (duration: 907.267µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:26.275563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.710315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T11:58:26.275599Z","caller":"traceutil/trace.go:171","msg":"trace[771727965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25913; }","duration":"130.778497ms","start":"2026-08-11T11:58:26.144811Z","end":"2026-08-11T11:58:26.27559Z","steps":["trace[771727965] 'agreement among raft nodes before linearized reading' (duration: 130.624924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:26.275637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.661085ms","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-08-11T11:58:26.275695Z","caller":"traceutil/trace.go:171","msg":"trace[1630253923] 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:25913; }","duration":"197.791437ms","start":"2026-08-11T11:58:26.077886Z","end":"2026-08-11T11:58:26.275677Z","steps":["trace[1630253923] 'agreement among raft nodes before linearized reading' (duration: 197.665475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:26.275717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.801269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-11T11:58:26.27574Z","caller":"traceutil/trace.go:171","msg":"trace[779728010] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25913; }","duration":"302.845979ms","start":"2026-08-11T11:58:25.972884Z","end":"2026-08-11T11:58:26.27573Z","steps":["trace[779728010] 'agreement among raft nodes before linearized reading' (duration: 302.782378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:26.275766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:25.972851Z","time spent":"302.909291ms","remote":"127.0.0.1:57934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-08-11T11:58:26.275502Z","caller":"traceutil/trace.go:171","msg":"trace[72138415] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"150.078328ms","start":"2026-08-11T11:58:26.125389Z","end":"2026-08-11T11:58:26.275468Z","steps":["trace[72138415] 'process raft request' (duration: 149.823734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:26.275941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.899833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T11:58:26.276132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.38847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:26.276148Z","caller":"traceutil/trace.go:171","msg":"trace[132181292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25913; }","duration":"113.122008ms","start":"2026-08-11T11:58:26.162972Z","end":"2026-08-11T11:58:26.276094Z","steps":["trace[132181292] 'agreement among raft nodes before linearized reading' (duration: 112.929424ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.276299Z","caller":"traceutil/trace.go:171","msg":"trace[198205267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25913; }","duration":"263.585894ms","start":"2026-08-11T11:58:26.012655Z","end":"2026-08-11T11:58:26.276241Z","steps":["trace[198205267] 'agreement among raft nodes before linearized reading' (duration: 263.312609ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.487926Z","caller":"traceutil/trace.go:171","msg":"trace[2008433576] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"151.826743ms","start":"2026-08-11T11:58:26.336063Z","end":"2026-08-11T11:58:26.48789Z","steps":["trace[2008433576] 'process raft request' (duration: 151.70176ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:26.487966Z","caller":"traceutil/trace.go:171","msg":"trace[434106586] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"152.033797ms","start":"2026-08-11T11:58:26.335861Z","end":"2026-08-11T11:58:26.487895Z","steps":["trace[434106586] 'process raft request' (duration: 139.342213ms)","trace[434106586] 'compare' (duration: 12.296786ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:27.265739Z","caller":"traceutil/trace.go:171","msg":"trace[2016990706] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"164.884744ms","start":"2026-08-11T11:58:27.100828Z","end":"2026-08-11T11:58:27.265712Z","steps":["trace[2016990706] 'process raft request' (duration: 164.719291ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:27.287562Z","caller":"traceutil/trace.go:171","msg":"trace[1134890806] linearizableReadLoop","detail":"{readStateIndex:26777; appliedIndex:26775; }","duration":"107.416948ms","start":"2026-08-11T11:58:27.180125Z","end":"2026-08-11T11:58:27.287542Z","steps":["trace[1134890806] 'read index received' (duration: 85.424974ms)","trace[1134890806] 'applied index is now lower than readState.Index' (duration: 21.990634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:27.287735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.576742ms","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-08-11T11:58:27.287703Z","caller":"traceutil/trace.go:171","msg":"trace[398055844] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"186.652013ms","start":"2026-08-11T11:58:27.101003Z","end":"2026-08-11T11:58:27.287655Z","steps":["trace[398055844] 'process raft request' (duration: 186.434899ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:27.287786Z","caller":"traceutil/trace.go:171","msg":"trace[1719959655] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25919; }","duration":"107.694433ms","start":"2026-08-11T11:58:27.180076Z","end":"2026-08-11T11:58:27.287771Z","steps":["trace[1719959655] 'agreement among raft nodes before linearized reading' (duration: 107.576391ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:27.906157Z","caller":"traceutil/trace.go:171","msg":"trace[1201480557] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"123.014137ms","start":"2026-08-11T11:58:27.783111Z","end":"2026-08-11T11:58:27.906125Z","steps":["trace[1201480557] 'process raft request' (duration: 122.805853ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.076762Z","caller":"traceutil/trace.go:171","msg":"trace[1393026514] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"122.478486ms","start":"2026-08-11T11:58:27.95425Z","end":"2026-08-11T11:58:28.076728Z","steps":["trace[1393026514] 'process raft request' (duration: 122.15594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:28.344549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.275026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:58:28.344771Z","caller":"traceutil/trace.go:171","msg":"trace[447861462] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"390.356814ms","start":"2026-08-11T11:58:27.954379Z","end":"2026-08-11T11:58:28.344736Z","steps":["trace[447861462] 'process raft request' (duration: 216.784033ms)","trace[447861462] 'compare' (duration: 173.074051ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:28.344836Z","caller":"traceutil/trace.go:171","msg":"trace[625304896] linearizableReadLoop","detail":"{readStateIndex:26781; appliedIndex:26779; }","duration":"178.093848ms","start":"2026-08-11T11:58:28.166722Z","end":"2026-08-11T11:58:28.344816Z","steps":["trace[625304896] 'read index received' (duration: 4.441166ms)","trace[625304896] 'applied index is now lower than readState.Index' (duration: 173.651652ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:28.344948Z","caller":"traceutil/trace.go:171","msg":"trace[1125397669] transaction","detail":"{read_only:false; response_revision:25923; number_of_response:1; }","duration":"383.200366ms","start":"2026-08-11T11:58:27.961736Z","end":"2026-08-11T11:58:28.344936Z","steps":["trace[1125397669] 'process raft request' (duration: 382.941421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:28.344947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:27.954356Z","time spent":"390.489576ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:28.345022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:27.961721Z","time spent":"383.261807ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:28.345309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.564287ms","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-08-11T11:58:28.345469Z","caller":"traceutil/trace.go:171","msg":"trace[1383316628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25923; }","duration":"178.774051ms","start":"2026-08-11T11:58:28.166671Z","end":"2026-08-11T11:58:28.345445Z","steps":["trace[1383316628] 'agreement among raft nodes before linearized reading' (duration: 178.432924ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.41017Z","caller":"traceutil/trace.go:171","msg":"trace[2121313885] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"242.355435ms","start":"2026-08-11T11:58:28.167793Z","end":"2026-08-11T11:58:28.410149Z","steps":["trace[2121313885] 'process raft request' (duration: 242.222592ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.459293Z","caller":"traceutil/trace.go:171","msg":"trace[1095936575] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"160.358196ms","start":"2026-08-11T11:58:28.298905Z","end":"2026-08-11T11:58:28.459263Z","steps":["trace[1095936575] 'process raft request' (duration: 147.540239ms)","trace[1095936575] 'compare' (duration: 12.393749ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:28.459337Z","caller":"traceutil/trace.go:171","msg":"trace[2089347701] linearizableReadLoop","detail":"{readStateIndex:26783; appliedIndex:26781; }","duration":"114.405883ms","start":"2026-08-11T11:58:28.344871Z","end":"2026-08-11T11:58:28.459277Z","steps":["trace[2089347701] 'read index received' (duration: 65.174765ms)","trace[2089347701] 'applied index is now lower than readState.Index' (duration: 49.229638ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:28.459375Z","caller":"traceutil/trace.go:171","msg":"trace[1079243400] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"109.63897ms","start":"2026-08-11T11:58:28.349683Z","end":"2026-08-11T11:58:28.459322Z","steps":["trace[1079243400] 'process raft request' (duration: 109.434627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:28.459613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.878874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-11T11:58:28.459671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.901533ms","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-08-11T11:58:28.45973Z","caller":"traceutil/trace.go:171","msg":"trace[1057558983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25926; }","duration":"151.056817ms","start":"2026-08-11T11:58:28.308628Z","end":"2026-08-11T11:58:28.459685Z","steps":["trace[1057558983] 'agreement among raft nodes before linearized reading' (duration: 150.716661ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.459771Z","caller":"traceutil/trace.go:171","msg":"trace[1882394638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25926; }","duration":"179.104697ms","start":"2026-08-11T11:58:28.280643Z","end":"2026-08-11T11:58:28.459748Z","steps":["trace[1882394638] 'agreement among raft nodes before linearized reading' (duration: 178.793332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:28.728858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.910157ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-11T11:58:28.730025Z","caller":"traceutil/trace.go:171","msg":"trace[619128092] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26784; }","duration":"236.596395ms","start":"2026-08-11T11:58:28.493405Z","end":"2026-08-11T11:58:28.730001Z","steps":["trace[619128092] 'read index received' (duration: 100.538326ms)","trace[619128092] 'applied index is now lower than readState.Index' (duration: 136.056569ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:28.730086Z","caller":"traceutil/trace.go:171","msg":"trace[1076057612] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"265.136084ms","start":"2026-08-11T11:58:28.464909Z","end":"2026-08-11T11:58:28.730045Z","steps":["trace[1076057612] 'process raft request' (duration: 264.837327ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.730099Z","caller":"traceutil/trace.go:171","msg":"trace[27719045] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"264.654004ms","start":"2026-08-11T11:58:28.465409Z","end":"2026-08-11T11:58:28.730063Z","steps":["trace[27719045] 'process raft request' (duration: 264.526822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:28.730371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.926841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-11T11:58:28.730514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.028884ms","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-08-11T11:58:28.730574Z","caller":"traceutil/trace.go:171","msg":"trace[1069118962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25928; }","duration":"237.216447ms","start":"2026-08-11T11:58:28.49333Z","end":"2026-08-11T11:58:28.730546Z","steps":["trace[1069118962] 'agreement among raft nodes before linearized reading' (duration: 236.761968ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:28.730608Z","caller":"traceutil/trace.go:171","msg":"trace[1526150421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25928; }","duration":"236.172977ms","start":"2026-08-11T11:58:28.494414Z","end":"2026-08-11T11:58:28.730587Z","steps":["trace[1526150421] 'agreement among raft nodes before linearized reading' (duration: 235.924033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:29.468498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.707637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:58:29.468631Z","caller":"traceutil/trace.go:171","msg":"trace[1343638741] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"173.51185ms","start":"2026-08-11T11:58:29.295097Z","end":"2026-08-11T11:58:29.468609Z","steps":["trace[1343638741] 'compare' (duration: 168.506613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:29.814013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.601276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T11:58:29.814127Z","caller":"traceutil/trace.go:171","msg":"trace[1587321836] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25933; }","duration":"233.77276ms","start":"2026-08-11T11:58:29.58033Z","end":"2026-08-11T11:58:29.814103Z","steps":["trace[1587321836] 'count revisions from in-memory index tree' (duration: 233.494455ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:34.724832Z","caller":"traceutil/trace.go:171","msg":"trace[252664907] linearizableReadLoop","detail":"{readStateIndex:26828; appliedIndex:26827; }","duration":"238.486341ms","start":"2026-08-11T11:58:34.48631Z","end":"2026-08-11T11:58:34.724796Z","steps":["trace[252664907] 'read index received' (duration: 235.32257ms)","trace[252664907] 'applied index is now lower than readState.Index' (duration: 3.162401ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:34.724909Z","caller":"traceutil/trace.go:171","msg":"trace[1249183703] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"239.124794ms","start":"2026-08-11T11:58:34.485735Z","end":"2026-08-11T11:58:34.72486Z","steps":["trace[1249183703] 'process raft request' (duration: 235.918202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:34.72516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.33925ms","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":"warn","ts":"2026-08-11T11:58:34.725226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.890469ms","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-08-11T11:58:34.725237Z","caller":"traceutil/trace.go:171","msg":"trace[1705677011] 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:25968; }","duration":"116.484443ms","start":"2026-08-11T11:58:34.608735Z","end":"2026-08-11T11:58:34.725219Z","steps":["trace[1705677011] 'agreement among raft nodes before linearized reading' (duration: 116.35021ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:34.72528Z","caller":"traceutil/trace.go:171","msg":"trace[767769463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25968; }","duration":"239.029042ms","start":"2026-08-11T11:58:34.486238Z","end":"2026-08-11T11:58:34.725267Z","steps":["trace[767769463] 'agreement among raft nodes before linearized reading' (duration: 238.769037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.190689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.110355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:58:35.190838Z","caller":"traceutil/trace.go:171","msg":"trace[578871245] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"430.865004ms","start":"2026-08-11T11:58:34.759947Z","end":"2026-08-11T11:58:35.190812Z","steps":["trace[578871245] 'process raft request' (duration: 248.494724ms)","trace[578871245] 'compare' (duration: 181.950022ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:35.191459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:34.759931Z","time spent":"431.328612ms","remote":"127.0.0.1:58026","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-08-11T11:58:35.20312Z","caller":"traceutil/trace.go:171","msg":"trace[1192050703] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"442.338215ms","start":"2026-08-11T11:58:34.760758Z","end":"2026-08-11T11:58:35.203097Z","steps":["trace[1192050703] 'process raft request' (duration: 441.995809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.203322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:34.760741Z","time spent":"442.457687ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:35.203465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.146317ms","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-08-11T11:58:35.203531Z","caller":"traceutil/trace.go:171","msg":"trace[864374335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25971; }","duration":"433.234479ms","start":"2026-08-11T11:58:34.770278Z","end":"2026-08-11T11:58:35.203512Z","steps":["trace[864374335] 'agreement among raft nodes before linearized reading' (duration: 432.930523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.203579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:34.770265Z","time spent":"433.30467ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T11:58:35.203056Z","caller":"traceutil/trace.go:171","msg":"trace[1349443974] linearizableReadLoop","detail":"{readStateIndex:26831; appliedIndex:26829; }","duration":"432.722079ms","start":"2026-08-11T11:58:34.770307Z","end":"2026-08-11T11:58:35.203029Z","steps":["trace[1349443974] 'read index received' (duration: 238.130854ms)","trace[1349443974] 'applied index is now lower than readState.Index' (duration: 194.589565ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:35.204289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.163728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-11T11:58:35.204341Z","caller":"traceutil/trace.go:171","msg":"trace[149332142] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25971; }","duration":"114.251039ms","start":"2026-08-11T11:58:35.090077Z","end":"2026-08-11T11:58:35.204328Z","steps":["trace[149332142] 'agreement among raft nodes before linearized reading' (duration: 113.963084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.204427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.693118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T11:58:35.204504Z","caller":"traceutil/trace.go:171","msg":"trace[1768244973] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25971; }","duration":"168.81473ms","start":"2026-08-11T11:58:35.035672Z","end":"2026-08-11T11:58:35.204487Z","steps":["trace[1768244973] 'agreement among raft nodes before linearized reading' (duration: 168.610626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.204823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.795954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T11:58:35.204917Z","caller":"traceutil/trace.go:171","msg":"trace[556020840] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25971; }","duration":"148.946657ms","start":"2026-08-11T11:58:35.055955Z","end":"2026-08-11T11:58:35.204901Z","steps":["trace[556020840] 'agreement among raft nodes before linearized reading' (duration: 148.813735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.205317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.985204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:35.205408Z","caller":"traceutil/trace.go:171","msg":"trace[2111245772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25971; }","duration":"262.141226ms","start":"2026-08-11T11:58:34.943248Z","end":"2026-08-11T11:58:35.205389Z","steps":["trace[2111245772] 'agreement among raft nodes before linearized reading' (duration: 261.999684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.205706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.249768ms","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-08-11T11:58:35.205756Z","caller":"traceutil/trace.go:171","msg":"trace[1547878029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25971; }","duration":"435.327239ms","start":"2026-08-11T11:58:34.770416Z","end":"2026-08-11T11:58:35.205743Z","steps":["trace[1547878029] 'agreement among raft nodes before linearized reading' (duration: 433.602506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.205792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:34.770401Z","time spent":"435.38363ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:58:35.782219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.590767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:58:35.782658Z","caller":"traceutil/trace.go:171","msg":"trace[1823159793] linearizableReadLoop","detail":"{readStateIndex:26835; appliedIndex:26833; }","duration":"445.160949ms","start":"2026-08-11T11:58:35.337468Z","end":"2026-08-11T11:58:35.782629Z","steps":["trace[1823159793] 'read index received' (duration: 159.017521ms)","trace[1823159793] 'applied index is now lower than readState.Index' (duration: 286.141598ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:35.782813Z","caller":"traceutil/trace.go:171","msg":"trace[1659908937] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"536.565778ms","start":"2026-08-11T11:58:35.246222Z","end":"2026-08-11T11:58:35.782788Z","steps":["trace[1659908937] 'process raft request' (duration: 250.258787ms)","trace[1659908937] 'compare' (duration: 284.747801ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:35.782916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:35.246195Z","time spent":"536.66785ms","remote":"127.0.0.1:58026","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-08-11T11:58:35.783743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.21268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:35.783758Z","caller":"traceutil/trace.go:171","msg":"trace[483734608] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"455.669411ms","start":"2026-08-11T11:58:35.328061Z","end":"2026-08-11T11:58:35.783731Z","steps":["trace[483734608] 'process raft request' (duration: 454.340225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.783752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.25832ms","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-08-11T11:58:35.783807Z","caller":"traceutil/trace.go:171","msg":"trace[1391880121] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25976; }","duration":"377.333944ms","start":"2026-08-11T11:58:35.406455Z","end":"2026-08-11T11:58:35.783789Z","steps":["trace[1391880121] 'agreement among raft nodes before linearized reading' (duration: 377.19095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.783837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:35.328032Z","time spent":"455.764704ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:58:35.783838Z","caller":"traceutil/trace.go:171","msg":"trace[891343712] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25976; }","duration":"446.428374ms","start":"2026-08-11T11:58:35.337388Z","end":"2026-08-11T11:58:35.783817Z","steps":["trace[891343712] 'agreement among raft nodes before linearized reading' (duration: 446.203689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.78385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:35.406416Z","time spent":"377.424966ms","remote":"127.0.0.1:53528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-08-11T11:58:35.783906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:35.337359Z","time spent":"446.525495ms","remote":"127.0.0.1:58274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-11T11:58:35.783945Z","caller":"traceutil/trace.go:171","msg":"trace[1459512546] transaction","detail":"{read_only:false; response_revision:25976; number_of_response:1; }","duration":"295.141372ms","start":"2026-08-11T11:58:35.488778Z","end":"2026-08-11T11:58:35.783919Z","steps":["trace[1459512546] 'process raft request' (duration: 293.783755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:35.783994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.41286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:35.784095Z","caller":"traceutil/trace.go:171","msg":"trace[803693580] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25976; }","duration":"198.579643ms","start":"2026-08-11T11:58:35.585485Z","end":"2026-08-11T11:58:35.784065Z","steps":["trace[803693580] 'agreement among raft nodes before linearized reading' (duration: 198.356519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:36.059239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.243909ms","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-08-11T11:58:36.05933Z","caller":"traceutil/trace.go:171","msg":"trace[1818715649] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25976; }","duration":"128.419992ms","start":"2026-08-11T11:58:35.930888Z","end":"2026-08-11T11:58:36.059308Z","steps":["trace[1818715649] 'range keys from in-memory index tree' (duration: 127.979764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:36.059518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.603925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:36.059629Z","caller":"traceutil/trace.go:171","msg":"trace[1476334410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25976; }","duration":"115.732358ms","start":"2026-08-11T11:58:35.943873Z","end":"2026-08-11T11:58:36.059605Z","steps":["trace[1476334410] 'range keys from in-memory index tree' (duration: 115.504693ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:36.153843Z","caller":"traceutil/trace.go:171","msg":"trace[1290409463] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"142.506563ms","start":"2026-08-11T11:58:36.011302Z","end":"2026-08-11T11:58:36.153808Z","steps":["trace[1290409463] 'process raft request' (duration: 142.202557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:45.024491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.994201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:45.024633Z","caller":"traceutil/trace.go:171","msg":"trace[70059433] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26100; }","duration":"145.173425ms","start":"2026-08-11T11:58:44.879426Z","end":"2026-08-11T11:58:45.024599Z","steps":["trace[70059433] 'count revisions from in-memory index tree' (duration: 144.862319ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:45.222194Z","caller":"traceutil/trace.go:171","msg":"trace[948682314] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"190.093969ms","start":"2026-08-11T11:58:45.031819Z","end":"2026-08-11T11:58:45.221913Z","steps":["trace[948682314] 'process raft request' (duration: 189.903935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:45.401097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.051658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T11:58:45.401175Z","caller":"traceutil/trace.go:171","msg":"trace[306477993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26101; }","duration":"164.19442ms","start":"2026-08-11T11:58:45.236965Z","end":"2026-08-11T11:58:45.401159Z","steps":["trace[306477993] 'range keys from in-memory index tree' (duration: 163.878035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:45.401285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.658225ms","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-08-11T11:58:45.401364Z","caller":"traceutil/trace.go:171","msg":"trace[810924215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26101; }","duration":"144.784057ms","start":"2026-08-11T11:58:45.256564Z","end":"2026-08-11T11:58:45.401348Z","steps":["trace[810924215] 'range keys from in-memory index tree' (duration: 144.504712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:45.401564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.607753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T11:58:45.401606Z","caller":"traceutil/trace.go:171","msg":"trace[517411912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26101; }","duration":"146.700114ms","start":"2026-08-11T11:58:45.254894Z","end":"2026-08-11T11:58:45.401595Z","steps":["trace[517411912] 'range keys from in-memory index tree' (duration: 146.059122ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:45.569206Z","caller":"traceutil/trace.go:171","msg":"trace[684724075] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"162.123611ms","start":"2026-08-11T11:58:45.407049Z","end":"2026-08-11T11:58:45.569173Z","steps":["trace[684724075] 'process raft request' (duration: 161.924887ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:45.74501Z","caller":"traceutil/trace.go:171","msg":"trace[239967119] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"337.830503ms","start":"2026-08-11T11:58:45.407139Z","end":"2026-08-11T11:58:45.744969Z","steps":["trace[239967119] 'process raft request' (duration: 264.576163ms)","trace[239967119] 'compare' (duration: 72.984195ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:45.745287Z","caller":"traceutil/trace.go:171","msg":"trace[615949385] linearizableReadLoop","detail":"{readStateIndex:26968; appliedIndex:26965; }","duration":"119.590711ms","start":"2026-08-11T11:58:45.625671Z","end":"2026-08-11T11:58:45.745262Z","steps":["trace[615949385] 'read index received' (duration: 46.007685ms)","trace[615949385] 'applied index is now lower than readState.Index' (duration: 73.581536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:45.745318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:45.407109Z","time spent":"338.006865ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:45.745441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.751146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:45.745509Z","caller":"traceutil/trace.go:171","msg":"trace[393920826] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26105; }","duration":"119.884028ms","start":"2026-08-11T11:58:45.62561Z","end":"2026-08-11T11:58:45.745494Z","steps":["trace[393920826] 'agreement among raft nodes before linearized reading' (duration: 119.753564ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:45.745501Z","caller":"traceutil/trace.go:171","msg":"trace[1494218517] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"337.827103ms","start":"2026-08-11T11:58:45.407649Z","end":"2026-08-11T11:58:45.745451Z","steps":["trace[1494218517] 'process raft request' (duration: 337.268092ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:45.745579Z","caller":"traceutil/trace.go:171","msg":"trace[800406908] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"337.434946ms","start":"2026-08-11T11:58:45.40811Z","end":"2026-08-11T11:58:45.745545Z","steps":["trace[800406908] 'process raft request' (duration: 337.003646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:45.745664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:45.407614Z","time spent":"337.957166ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:45.745753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:45.408075Z","time spent":"337.585629ms","remote":"127.0.0.1:58026","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-08-11T11:58:46.028368Z","caller":"traceutil/trace.go:171","msg":"trace[1009424811] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26968; }","duration":"215.156351ms","start":"2026-08-11T11:58:45.81318Z","end":"2026-08-11T11:58:46.028337Z","steps":["trace[1009424811] 'read index received' (duration: 214.956487ms)","trace[1009424811] 'applied index is now lower than readState.Index' (duration: 198.254µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:46.028447Z","caller":"traceutil/trace.go:171","msg":"trace[795602931] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"215.483977ms","start":"2026-08-11T11:58:45.812929Z","end":"2026-08-11T11:58:46.028413Z","steps":["trace[795602931] 'process raft request' (duration: 215.190291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.028694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.486078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"info","ts":"2026-08-11T11:58:46.02883Z","caller":"traceutil/trace.go:171","msg":"trace[185020729] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26106; }","duration":"215.687002ms","start":"2026-08-11T11:58:45.81312Z","end":"2026-08-11T11:58:46.028807Z","steps":["trace[185020729] 'agreement among raft nodes before linearized reading' (duration: 215.324445ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.052655Z","caller":"traceutil/trace.go:171","msg":"trace[1658202941] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"219.825271ms","start":"2026-08-11T11:58:45.832806Z","end":"2026-08-11T11:58:46.052631Z","steps":["trace[1658202941] 'process raft request' (duration: 219.621077ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.052999Z","caller":"traceutil/trace.go:171","msg":"trace[1755801512] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"216.805993ms","start":"2026-08-11T11:58:45.836168Z","end":"2026-08-11T11:58:46.052974Z","steps":["trace[1755801512] 'process raft request' (duration: 216.405405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.053022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.711502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:46.053101Z","caller":"traceutil/trace.go:171","msg":"trace[637510398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26108; }","duration":"109.841824ms","start":"2026-08-11T11:58:45.943241Z","end":"2026-08-11T11:58:46.053083Z","steps":["trace[637510398] 'agreement among raft nodes before linearized reading' (duration: 109.66573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.053073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.558632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T11:58:46.053213Z","caller":"traceutil/trace.go:171","msg":"trace[95507024] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26108; }","duration":"145.745166ms","start":"2026-08-11T11:58:45.907453Z","end":"2026-08-11T11:58:46.053199Z","steps":["trace[95507024] 'agreement among raft nodes before linearized reading' (duration: 145.481311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.053287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.919438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25631a0e-e0b3-486f-9ea6-922fdc5947e1\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-11T11:58:46.053321Z","caller":"traceutil/trace.go:171","msg":"trace[554759247] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25631a0e-e0b3-486f-9ea6-922fdc5947e1; range_end:; response_count:1; response_revision:26108; }","duration":"225.979569ms","start":"2026-08-11T11:58:45.827333Z","end":"2026-08-11T11:58:46.053312Z","steps":["trace[554759247] 'agreement among raft nodes before linearized reading' (duration: 225.858757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.05316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.30178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:46.053393Z","caller":"traceutil/trace.go:171","msg":"trace[2094097117] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26108; }","duration":"234.566864ms","start":"2026-08-11T11:58:45.81881Z","end":"2026-08-11T11:58:46.053377Z","steps":["trace[2094097117] 'agreement among raft nodes before linearized reading' (duration: 234.295999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.054361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.493383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273011"} {"level":"info","ts":"2026-08-11T11:58:46.05442Z","caller":"traceutil/trace.go:171","msg":"trace[1326069056] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26108; }","duration":"155.611146ms","start":"2026-08-11T11:58:45.898795Z","end":"2026-08-11T11:58:46.054407Z","steps":["trace[1326069056] 'agreement among raft nodes before linearized reading' (duration: 154.344411ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.40633Z","caller":"traceutil/trace.go:171","msg":"trace[531509413] linearizableReadLoop","detail":"{readStateIndex:26972; appliedIndex:26971; }","duration":"347.700563ms","start":"2026-08-11T11:58:46.058595Z","end":"2026-08-11T11:58:46.406296Z","steps":["trace[531509413] 'read index received' (duration: 347.472688ms)","trace[531509413] 'applied index is now lower than readState.Index' (duration: 226.285µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:46.406694Z","caller":"traceutil/trace.go:171","msg":"trace[844283612] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"349.115301ms","start":"2026-08-11T11:58:46.057564Z","end":"2026-08-11T11:58:46.406679Z","steps":["trace[844283612] 'process raft request' (duration: 348.519999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.406859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.05754Z","time spent":"349.203832ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:46.407096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.490099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-08-11T11:58:46.407136Z","caller":"traceutil/trace.go:171","msg":"trace[489211533] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26109; }","duration":"348.547059ms","start":"2026-08-11T11:58:46.058578Z","end":"2026-08-11T11:58:46.407125Z","steps":["trace[489211533] 'agreement among raft nodes before linearized reading' (duration: 348.342445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.40716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.058571Z","time spent":"348.583311ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18376,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-11T11:58:46.671648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.155665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:58:46.672048Z","caller":"traceutil/trace.go:171","msg":"trace[817301563] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"259.860381ms","start":"2026-08-11T11:58:46.412163Z","end":"2026-08-11T11:58:46.672024Z","steps":["trace[817301563] 'process raft request' (duration: 259.8034ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.672382Z","caller":"traceutil/trace.go:171","msg":"trace[424867011] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"471.465375ms","start":"2026-08-11T11:58:46.200895Z","end":"2026-08-11T11:58:46.672361Z","steps":["trace[424867011] 'process raft request' (duration: 272.457755ms)","trace[424867011] 'compare' (duration: 197.751127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:46.672459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.200828Z","time spent":"471.592237ms","remote":"127.0.0.1:58026","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-08-11T11:58:46.672564Z","caller":"traceutil/trace.go:171","msg":"trace[1425396023] linearizableReadLoop","detail":"{readStateIndex:26974; appliedIndex:26972; }","duration":"266.089502ms","start":"2026-08-11T11:58:46.406421Z","end":"2026-08-11T11:58:46.67251Z","steps":["trace[1425396023] 'read index received' (duration: 66.826457ms)","trace[1425396023] 'applied index is now lower than readState.Index' (duration: 199.254355ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:46.672628Z","caller":"traceutil/trace.go:171","msg":"trace[561346032] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"260.563986ms","start":"2026-08-11T11:58:46.412049Z","end":"2026-08-11T11:58:46.672613Z","steps":["trace[561346032] 'process raft request' (duration: 259.869863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.673079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.654892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:58:46.67317Z","caller":"traceutil/trace.go:171","msg":"trace[1776755799] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"471.881334ms","start":"2026-08-11T11:58:46.201276Z","end":"2026-08-11T11:58:46.673157Z","steps":["trace[1776755799] 'process raft request' (duration: 470.515067ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.673189Z","caller":"traceutil/trace.go:171","msg":"trace[139423109] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26113; }","duration":"613.796035ms","start":"2026-08-11T11:58:46.059367Z","end":"2026-08-11T11:58:46.673163Z","steps":["trace[139423109] 'agreement among raft nodes before linearized reading' (duration: 613.631902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.673249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.059353Z","time spent":"613.885687ms","remote":"127.0.0.1:57980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-08-11T11:58:46.673248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.201235Z","time spent":"471.977545ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:58:46.673127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.077845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-08-11T11:58:46.673371Z","caller":"traceutil/trace.go:171","msg":"trace[375080231] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26113; }","duration":"607.313411ms","start":"2026-08-11T11:58:46.066022Z","end":"2026-08-11T11:58:46.673335Z","steps":["trace[375080231] 'agreement among raft nodes before linearized reading' (duration: 606.944814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.673496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:46.066015Z","time spent":"607.436223ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9627,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-08-11T11:58:46.719584Z","caller":"traceutil/trace.go:171","msg":"trace[1327015669] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"182.87035ms","start":"2026-08-11T11:58:46.536676Z","end":"2026-08-11T11:58:46.719547Z","steps":["trace[1327015669] 'process raft request' (duration: 182.804568ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.720057Z","caller":"traceutil/trace.go:171","msg":"trace[1583958725] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"236.211957ms","start":"2026-08-11T11:58:46.483802Z","end":"2026-08-11T11:58:46.720014Z","steps":["trace[1583958725] 'process raft request' (duration: 235.495173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:46.720145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.699708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T11:58:46.720184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.520361ms","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-08-11T11:58:46.720261Z","caller":"traceutil/trace.go:171","msg":"trace[1307680431] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26115; }","duration":"225.640063ms","start":"2026-08-11T11:58:46.494604Z","end":"2026-08-11T11:58:46.720244Z","steps":["trace[1307680431] 'agreement among raft nodes before linearized reading' (duration: 225.426819ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:46.720259Z","caller":"traceutil/trace.go:171","msg":"trace[583445402] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26115; }","duration":"260.896202ms","start":"2026-08-11T11:58:46.459336Z","end":"2026-08-11T11:58:46.720232Z","steps":["trace[583445402] 'agreement among raft nodes before linearized reading' (duration: 260.700498ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:52.187203Z","caller":"traceutil/trace.go:171","msg":"trace[411616310] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"113.088356ms","start":"2026-08-11T11:58:52.074084Z","end":"2026-08-11T11:58:52.187173Z","steps":["trace[411616310] 'process raft request' (duration: 112.867232ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:52.274198Z","caller":"traceutil/trace.go:171","msg":"trace[1523703552] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"195.436662ms","start":"2026-08-11T11:58:52.07873Z","end":"2026-08-11T11:58:52.274167Z","steps":["trace[1523703552] 'process raft request' (duration: 171.126464ms)","trace[1523703552] 'compare' (duration: 23.84359ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:52.451638Z","caller":"traceutil/trace.go:171","msg":"trace[153740330] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"171.594693ms","start":"2026-08-11T11:58:52.280015Z","end":"2026-08-11T11:58:52.45161Z","steps":["trace[153740330] 'process raft request' (duration: 170.9178ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:53.301158Z","caller":"traceutil/trace.go:171","msg":"trace[1548305654] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"100.499045ms","start":"2026-08-11T11:58:53.200614Z","end":"2026-08-11T11:58:53.301113Z","steps":["trace[1548305654] 'process raft request' (duration: 100.218399ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:55.414054Z","caller":"traceutil/trace.go:171","msg":"trace[1976577998] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27076; }","duration":"133.093452ms","start":"2026-08-11T11:58:55.280929Z","end":"2026-08-11T11:58:55.414023Z","steps":["trace[1976577998] 'read index received' (duration: 132.884678ms)","trace[1976577998] 'applied index is now lower than readState.Index' (duration: 207.384µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:55.414343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.383928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-11T11:58:55.414387Z","caller":"traceutil/trace.go:171","msg":"trace[1834899345] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26212; }","duration":"133.524291ms","start":"2026-08-11T11:58:55.280846Z","end":"2026-08-11T11:58:55.414371Z","steps":["trace[1834899345] 'agreement among raft nodes before linearized reading' (duration: 133.293247ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:55.414464Z","caller":"traceutil/trace.go:171","msg":"trace[1281184096] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"165.125978ms","start":"2026-08-11T11:58:55.249317Z","end":"2026-08-11T11:58:55.414443Z","steps":["trace[1281184096] 'process raft request' (duration: 164.519467ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:58.966762Z","caller":"traceutil/trace.go:171","msg":"trace[1222305796] linearizableReadLoop","detail":"{readStateIndex:27106; appliedIndex:27101; }","duration":"106.244185ms","start":"2026-08-11T11:58:58.860483Z","end":"2026-08-11T11:58:58.966727Z","steps":["trace[1222305796] 'read index received' (duration: 87.521434ms)","trace[1222305796] 'applied index is now lower than readState.Index' (duration: 18.721351ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:58.96688Z","caller":"traceutil/trace.go:171","msg":"trace[1975989428] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"210.140765ms","start":"2026-08-11T11:58:58.756698Z","end":"2026-08-11T11:58:58.966838Z","steps":["trace[1975989428] 'process raft request' (duration: 209.903371ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:58.966947Z","caller":"traceutil/trace.go:171","msg":"trace[197185051] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"163.134ms","start":"2026-08-11T11:58:58.803797Z","end":"2026-08-11T11:58:58.966931Z","steps":["trace[197185051] 'process raft request' (duration: 162.868075ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:58.966889Z","caller":"traceutil/trace.go:171","msg":"trace[1266591694] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"244.262342ms","start":"2026-08-11T11:58:58.722591Z","end":"2026-08-11T11:58:58.966854Z","steps":["trace[1266591694] 'process raft request' (duration: 243.966076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:58.967371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.874217ms","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-08-11T11:58:58.96742Z","caller":"traceutil/trace.go:171","msg":"trace[591380279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26240; }","duration":"106.979259ms","start":"2026-08-11T11:58:58.860429Z","end":"2026-08-11T11:58:58.967408Z","steps":["trace[591380279] 'agreement among raft nodes before linearized reading' (duration: 106.791185ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:58.967418Z","caller":"traceutil/trace.go:171","msg":"trace[1051929132] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"250.326968ms","start":"2026-08-11T11:58:58.717036Z","end":"2026-08-11T11:58:58.967363Z","steps":["trace[1051929132] 'process raft request' (duration: 230.946815ms)","trace[1051929132] 'compare' (duration: 17.544258ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:58.967543Z","caller":"traceutil/trace.go:171","msg":"trace[53612456] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"249.836159ms","start":"2026-08-11T11:58:58.717571Z","end":"2026-08-11T11:58:58.967408Z","steps":["trace[53612456] 'process raft request' (duration: 248.862191ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:01.218135Z","caller":"traceutil/trace.go:171","msg":"trace[1488839847] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"144.44285ms","start":"2026-08-11T11:59:01.073642Z","end":"2026-08-11T11:59:01.218084Z","steps":["trace[1488839847] 'process raft request' (duration: 138.133139ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:16.069599Z","caller":"traceutil/trace.go:171","msg":"trace[1941786809] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"157.294268ms","start":"2026-08-11T11:59:15.912263Z","end":"2026-08-11T11:59:16.069557Z","steps":["trace[1941786809] 'process raft request' (duration: 85.598958ms)","trace[1941786809] 'compare' (duration: 71.442625ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:16.069732Z","caller":"traceutil/trace.go:171","msg":"trace[316942197] linearizableReadLoop","detail":"{readStateIndex:27245; appliedIndex:27243; }","duration":"126.680778ms","start":"2026-08-11T11:59:15.943016Z","end":"2026-08-11T11:59:16.069697Z","steps":["trace[316942197] 'read index received' (duration: 54.851086ms)","trace[316942197] 'applied index is now lower than readState.Index' (duration: 71.827572ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:16.069918Z","caller":"traceutil/trace.go:171","msg":"trace[719383410] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"157.586133ms","start":"2026-08-11T11:59:15.912279Z","end":"2026-08-11T11:59:16.069865Z","steps":["trace[719383410] 'process raft request' (duration: 157.223197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:16.070131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.098056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:59:16.070374Z","caller":"traceutil/trace.go:171","msg":"trace[1979945426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26376; }","duration":"127.235859ms","start":"2026-08-11T11:59:15.942946Z","end":"2026-08-11T11:59:16.070182Z","steps":["trace[1979945426] 'agreement among raft nodes before linearized reading' (duration: 127.103196ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:16.47016Z","caller":"traceutil/trace.go:171","msg":"trace[11996820] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"124.297452ms","start":"2026-08-11T11:59:16.345841Z","end":"2026-08-11T11:59:16.470139Z","steps":["trace[11996820] 'process raft request' (duration: 124.136869ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.000015Z","caller":"traceutil/trace.go:171","msg":"trace[252315438] linearizableReadLoop","detail":"{readStateIndex:27249; appliedIndex:27248; }","duration":"129.915971ms","start":"2026-08-11T11:59:16.870073Z","end":"2026-08-11T11:59:16.999989Z","steps":["trace[252315438] 'read index received' (duration: 67.845746ms)","trace[252315438] 'applied index is now lower than readState.Index' (duration: 62.068965ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:17.000194Z","caller":"traceutil/trace.go:171","msg":"trace[1751862171] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"131.448749ms","start":"2026-08-11T11:59:16.868715Z","end":"2026-08-11T11:59:17.000164Z","steps":["trace[1751862171] 'process raft request' (duration: 69.111119ms)","trace[1751862171] 'compare' (duration: 61.907632ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:17.000389Z","caller":"traceutil/trace.go:171","msg":"trace[1977867177] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"111.956856ms","start":"2026-08-11T11:59:16.888399Z","end":"2026-08-11T11:59:17.000355Z","steps":["trace[1977867177] 'process raft request' (duration: 111.540317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.000811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.232672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-11T11:59:17.000941Z","caller":"traceutil/trace.go:171","msg":"trace[1510049277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26381; }","duration":"110.435576ms","start":"2026-08-11T11:59:16.890484Z","end":"2026-08-11T11:59:17.000919Z","steps":["trace[1510049277] 'agreement among raft nodes before linearized reading' (duration: 109.899096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.001096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.499032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:59:17.001161Z","caller":"traceutil/trace.go:171","msg":"trace[1687786284] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26381; }","duration":"131.131785ms","start":"2026-08-11T11:59:16.870012Z","end":"2026-08-11T11:59:17.001144Z","steps":["trace[1687786284] 'agreement among raft nodes before linearized reading' (duration: 130.33867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.225094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.433265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-08-11T11:59:17.225148Z","caller":"traceutil/trace.go:171","msg":"trace[702200484] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26381; }","duration":"125.504456ms","start":"2026-08-11T11:59:17.099632Z","end":"2026-08-11T11:59:17.225136Z","steps":["trace[702200484] 'range keys from in-memory index tree' (duration: 125.308762ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.225229Z","caller":"traceutil/trace.go:171","msg":"trace[1496829544] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"110.682842ms","start":"2026-08-11T11:59:17.114523Z","end":"2026-08-11T11:59:17.225206Z","steps":["trace[1496829544] 'process raft request' (duration: 34.825452ms)","trace[1496829544] 'compare' (duration: 75.628355ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:17.274888Z","caller":"traceutil/trace.go:171","msg":"trace[1948460995] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"159.661813ms","start":"2026-08-11T11:59:17.115199Z","end":"2026-08-11T11:59:17.274861Z","steps":["trace[1948460995] 'process raft request' (duration: 159.50924ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.297314Z","caller":"traceutil/trace.go:171","msg":"trace[1387336915] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"137.463397ms","start":"2026-08-11T11:59:17.159815Z","end":"2026-08-11T11:59:17.297279Z","steps":["trace[1387336915] 'process raft request' (duration: 137.201521ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.297318Z","caller":"traceutil/trace.go:171","msg":"trace[609906123] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"137.458187ms","start":"2026-08-11T11:59:17.159829Z","end":"2026-08-11T11:59:17.297287Z","steps":["trace[609906123] 'process raft request' (duration: 137.367305ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.297498Z","caller":"traceutil/trace.go:171","msg":"trace[1909141574] linearizableReadLoop","detail":"{readStateIndex:27255; appliedIndex:27251; }","duration":"113.773081ms","start":"2026-08-11T11:59:17.183699Z","end":"2026-08-11T11:59:17.297472Z","steps":["trace[1909141574] 'read index received' (duration: 91.026052ms)","trace[1909141574] 'applied index is now lower than readState.Index' (duration: 22.744429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:59:17.297606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.905463ms","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-08-11T11:59:17.297637Z","caller":"traceutil/trace.go:171","msg":"trace[1870784207] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26386; }","duration":"113.971754ms","start":"2026-08-11T11:59:17.18366Z","end":"2026-08-11T11:59:17.297631Z","steps":["trace[1870784207] 'agreement among raft nodes before linearized reading' (duration: 113.890092ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.29752Z","caller":"traceutil/trace.go:171","msg":"trace[1301939604] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"137.463596ms","start":"2026-08-11T11:59:17.160043Z","end":"2026-08-11T11:59:17.297507Z","steps":["trace[1301939604] 'process raft request' (duration: 137.202141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.472139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.03068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:59:17.472286Z","caller":"traceutil/trace.go:171","msg":"trace[992564907] linearizableReadLoop","detail":"{readStateIndex:27256; appliedIndex:27255; }","duration":"164.105739ms","start":"2026-08-11T11:59:17.308163Z","end":"2026-08-11T11:59:17.472269Z","steps":["trace[992564907] 'read index received' (duration: 55.897576ms)","trace[992564907] 'applied index is now lower than readState.Index' (duration: 108.206153ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:17.472344Z","caller":"traceutil/trace.go:171","msg":"trace[1442474031] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"170.268608ms","start":"2026-08-11T11:59:17.302034Z","end":"2026-08-11T11:59:17.472303Z","steps":["trace[1442474031] 'process raft request' (duration: 61.979703ms)","trace[1442474031] 'compare' (duration: 107.831716ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:59:17.472484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.321373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-08-11T11:59:17.472512Z","caller":"traceutil/trace.go:171","msg":"trace[1922489330] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26387; }","duration":"164.345244ms","start":"2026-08-11T11:59:17.308152Z","end":"2026-08-11T11:59:17.472498Z","steps":["trace[1922489330] 'agreement among raft nodes before linearized reading' (duration: 164.183871ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:17.585666Z","caller":"traceutil/trace.go:171","msg":"trace[869026643] linearizableReadLoop","detail":"{readStateIndex:27257; appliedIndex:27256; }","duration":"113.330341ms","start":"2026-08-11T11:59:17.472309Z","end":"2026-08-11T11:59:17.585639Z","steps":["trace[869026643] 'read index received' (duration: 113.110757ms)","trace[869026643] 'applied index is now lower than readState.Index' (duration: 218.484µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:17.585707Z","caller":"traceutil/trace.go:171","msg":"trace[1011414070] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"126.354812ms","start":"2026-08-11T11:59:17.459307Z","end":"2026-08-11T11:59:17.585662Z","steps":["trace[1011414070] 'process raft request' (duration: 126.166759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.585808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.056426ms","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-08-11T11:59:17.58592Z","caller":"traceutil/trace.go:171","msg":"trace[1303854976] 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:26388; }","duration":"126.243ms","start":"2026-08-11T11:59:17.459655Z","end":"2026-08-11T11:59:17.585898Z","steps":["trace[1303854976] 'agreement among raft nodes before linearized reading' (duration: 126.016684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.585957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.715196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-08-11T11:59:17.586004Z","caller":"traceutil/trace.go:171","msg":"trace[846714581] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26388; }","duration":"104.805117ms","start":"2026-08-11T11:59:17.48118Z","end":"2026-08-11T11:59:17.585985Z","steps":["trace[846714581] 'agreement among raft nodes before linearized reading' (duration: 104.616994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:17.586049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.535408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T11:59:17.586151Z","caller":"traceutil/trace.go:171","msg":"trace[63604085] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26388; }","duration":"244.710331ms","start":"2026-08-11T11:59:17.341415Z","end":"2026-08-11T11:59:17.586125Z","steps":["trace[63604085] 'agreement among raft nodes before linearized reading' (duration: 244.371015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:18.065127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.786207ms","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-08-11T11:59:18.065217Z","caller":"traceutil/trace.go:171","msg":"trace[1594360394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26389; }","duration":"131.94326ms","start":"2026-08-11T11:59:17.933257Z","end":"2026-08-11T11:59:18.0652Z","steps":["trace[1594360394] 'range keys from in-memory index tree' (duration: 131.615763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:18.065525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.56865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:59:18.065615Z","caller":"traceutil/trace.go:171","msg":"trace[1314158886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26389; }","duration":"121.683662ms","start":"2026-08-11T11:59:17.943912Z","end":"2026-08-11T11:59:18.065595Z","steps":["trace[1314158886] 'range keys from in-memory index tree' (duration: 121.494449ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:18.291644Z","caller":"traceutil/trace.go:171","msg":"trace[975604527] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"146.711074ms","start":"2026-08-11T11:59:18.144906Z","end":"2026-08-11T11:59:18.291617Z","steps":["trace[975604527] 'process raft request' (duration: 146.662403ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:18.291786Z","caller":"traceutil/trace.go:171","msg":"trace[1746181873] transaction","detail":"{read_only:false; response_revision:26391; number_of_response:1; }","duration":"147.672263ms","start":"2026-08-11T11:59:18.144076Z","end":"2026-08-11T11:59:18.291748Z","steps":["trace[1746181873] 'process raft request' (duration: 68.022189ms)","trace[1746181873] 'compare' (duration: 79.330957ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:19.2041Z","caller":"traceutil/trace.go:171","msg":"trace[248913526] linearizableReadLoop","detail":"{readStateIndex:27266; appliedIndex:27265; }","duration":"110.359974ms","start":"2026-08-11T11:59:19.093714Z","end":"2026-08-11T11:59:19.204074Z","steps":["trace[248913526] 'read index received' (duration: 110.16408ms)","trace[248913526] 'applied index is now lower than readState.Index' (duration: 194.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:19.204259Z","caller":"traceutil/trace.go:171","msg":"trace[1445412980] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"121.477778ms","start":"2026-08-11T11:59:19.082758Z","end":"2026-08-11T11:59:19.204236Z","steps":["trace[1445412980] 'process raft request' (duration: 121.140842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:19.204615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.861943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:59:19.204742Z","caller":"traceutil/trace.go:171","msg":"trace[728201697] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:26396; }","duration":"111.051787ms","start":"2026-08-11T11:59:19.093651Z","end":"2026-08-11T11:59:19.204703Z","steps":["trace[728201697] 'agreement among raft nodes before linearized reading' (duration: 110.851713ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:19.336566Z","caller":"traceutil/trace.go:171","msg":"trace[2031172604] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"104.324088ms","start":"2026-08-11T11:59:19.232147Z","end":"2026-08-11T11:59:19.336471Z","steps":["trace[2031172604] 'process raft request' (duration: 35.449183ms)","trace[2031172604] 'compare' (duration: 68.710212ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:19.430103Z","caller":"traceutil/trace.go:171","msg":"trace[1548253020] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"126.168218ms","start":"2026-08-11T11:59:19.303892Z","end":"2026-08-11T11:59:19.43006Z","steps":["trace[1548253020] 'process raft request' (duration: 125.521396ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:19.430426Z","caller":"traceutil/trace.go:171","msg":"trace[1904738752] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"126.405943ms","start":"2026-08-11T11:59:19.303986Z","end":"2026-08-11T11:59:19.430392Z","steps":["trace[1904738752] 'process raft request' (duration: 125.574968ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:19.430576Z","caller":"traceutil/trace.go:171","msg":"trace[1238395273] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"126.599177ms","start":"2026-08-11T11:59:19.303965Z","end":"2026-08-11T11:59:19.430564Z","steps":["trace[1238395273] 'process raft request' (duration: 125.631468ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:20.446785Z","caller":"traceutil/trace.go:171","msg":"trace[333453659] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"143.240077ms","start":"2026-08-11T11:59:20.30352Z","end":"2026-08-11T11:59:20.44676Z","steps":["trace[333453659] 'process raft request' (duration: 143.071374ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:20.474732Z","caller":"traceutil/trace.go:171","msg":"trace[707195775] linearizableReadLoop","detail":"{readStateIndex:27276; appliedIndex:27274; }","duration":"114.913442ms","start":"2026-08-11T11:59:20.359773Z","end":"2026-08-11T11:59:20.474687Z","steps":["trace[707195775] 'read index received' (duration: 86.810271ms)","trace[707195775] 'applied index is now lower than readState.Index' (duration: 28.101791ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:20.474774Z","caller":"traceutil/trace.go:171","msg":"trace[2010374944] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"171.033902ms","start":"2026-08-11T11:59:20.303706Z","end":"2026-08-11T11:59:20.47474Z","steps":["trace[2010374944] 'process raft request' (duration: 170.833038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:20.474895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.084885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T11:59:20.474946Z","caller":"traceutil/trace.go:171","msg":"trace[1856303426] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26406; }","duration":"115.190017ms","start":"2026-08-11T11:59:20.35974Z","end":"2026-08-11T11:59:20.47493Z","steps":["trace[1856303426] 'agreement among raft nodes before linearized reading' (duration: 115.083145ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:31.357451Z","caller":"traceutil/trace.go:171","msg":"trace[1557483030] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"100.710519ms","start":"2026-08-11T11:59:31.256698Z","end":"2026-08-11T11:59:31.357409Z","steps":["trace[1557483030] 'process raft request' (duration: 100.454543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:31.539518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.080294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T11:59:31.539862Z","caller":"traceutil/trace.go:171","msg":"trace[1091551266] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"155.742368ms","start":"2026-08-11T11:59:31.38408Z","end":"2026-08-11T11:59:31.539822Z","steps":["trace[1091551266] 'process raft request' (duration: 155.611606ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:31.540331Z","caller":"traceutil/trace.go:171","msg":"trace[1123223244] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"164.060019ms","start":"2026-08-11T11:59:31.37624Z","end":"2026-08-11T11:59:31.5403Z","steps":["trace[1123223244] 'process raft request' (duration: 58.039057ms)","trace[1123223244] 'compare' (duration: 104.180406ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:31.609752Z","caller":"traceutil/trace.go:171","msg":"trace[905507182] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"130.040704ms","start":"2026-08-11T11:59:31.479679Z","end":"2026-08-11T11:59:31.60972Z","steps":["trace[905507182] 'process raft request' (duration: 129.737518ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:31.609752Z","caller":"traceutil/trace.go:171","msg":"trace[959494770] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"129.404622ms","start":"2026-08-11T11:59:31.480314Z","end":"2026-08-11T11:59:31.609718Z","steps":["trace[959494770] 'process raft request' (duration: 129.30618ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:31.609954Z","caller":"traceutil/trace.go:171","msg":"trace[1040883978] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"127.746129ms","start":"2026-08-11T11:59:31.482155Z","end":"2026-08-11T11:59:31.609901Z","steps":["trace[1040883978] 'process raft request' (duration: 127.516215ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:31.857957Z","caller":"traceutil/trace.go:171","msg":"trace[65867363] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"114.059385ms","start":"2026-08-11T11:59:31.743852Z","end":"2026-08-11T11:59:31.857911Z","steps":["trace[65867363] 'process raft request' (duration: 113.769239ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:32.013525Z","caller":"traceutil/trace.go:171","msg":"trace[824525864] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"130.399181ms","start":"2026-08-11T11:59:31.883088Z","end":"2026-08-11T11:59:32.013487Z","steps":["trace[824525864] 'process raft request' (duration: 98.878804ms)","trace[824525864] 'compare' (duration: 31.349353ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:35.99218Z","caller":"traceutil/trace.go:171","msg":"trace[941019675] linearizableReadLoop","detail":"{readStateIndex:27402; appliedIndex:27401; }","duration":"117.93645ms","start":"2026-08-11T11:59:35.87422Z","end":"2026-08-11T11:59:35.992156Z","steps":["trace[941019675] 'read index received' (duration: 117.768107ms)","trace[941019675] 'applied index is now lower than readState.Index' (duration: 167.003µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:59:35.992302Z","caller":"traceutil/trace.go:171","msg":"trace[1742980673] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"179.510136ms","start":"2026-08-11T11:59:35.812746Z","end":"2026-08-11T11:59:35.992256Z","steps":["trace[1742980673] 'process raft request' (duration: 179.232421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:59:35.992452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.219596ms","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-08-11T11:59:35.9925Z","caller":"traceutil/trace.go:171","msg":"trace[228664745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26529; }","duration":"118.330708ms","start":"2026-08-11T11:59:35.874155Z","end":"2026-08-11T11:59:35.992486Z","steps":["trace[228664745] 'agreement among raft nodes before linearized reading' (duration: 118.151095ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:41.703858Z","caller":"traceutil/trace.go:171","msg":"trace[467896992] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"120.297246ms","start":"2026-08-11T11:59:41.583521Z","end":"2026-08-11T11:59:41.703818Z","steps":["trace[467896992] 'process raft request' (duration: 119.508481ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:41.803085Z","caller":"traceutil/trace.go:171","msg":"trace[740136973] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"219.117458ms","start":"2026-08-11T11:59:41.583931Z","end":"2026-08-11T11:59:41.803048Z","steps":["trace[740136973] 'process raft request' (duration: 218.780441ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:41.803389Z","caller":"traceutil/trace.go:171","msg":"trace[101444764] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"151.577347ms","start":"2026-08-11T11:59:41.651762Z","end":"2026-08-11T11:59:41.803339Z","steps":["trace[101444764] 'process raft request' (duration: 151.19404ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:41.803669Z","caller":"traceutil/trace.go:171","msg":"trace[1918416994] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"149.799164ms","start":"2026-08-11T11:59:41.653833Z","end":"2026-08-11T11:59:41.803633Z","steps":["trace[1918416994] 'process raft request' (duration: 149.476557ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:59:41.803754Z","caller":"traceutil/trace.go:171","msg":"trace[329131246] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"138.19223ms","start":"2026-08-11T11:59:41.66553Z","end":"2026-08-11T11:59:41.803723Z","steps":["trace[329131246] 'process raft request' (duration: 138.012417ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:01:55.119018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25064} {"level":"info","ts":"2026-08-11T12:01:55.267367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25064,"took":"143.503802ms","hash":2519536239,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":38162432,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-11T12:01:55.267629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2519536239,"revision":25064,"compact-revision":21864} {"level":"info","ts":"2026-08-11T12:03:41.512486Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T12:03:41.524442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-08-11T12:03:41.524587Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-08-11T12:06:55.13888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27991} {"level":"info","ts":"2026-08-11T12:06:55.289199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27991,"took":"143.9173ms","hash":2431060243,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":33988608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T12:06:55.28928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2431060243,"revision":27991,"compact-revision":25064} {"level":"info","ts":"2026-08-11T12:11:55.145191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30591} {"level":"info","ts":"2026-08-11T12:11:55.289286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30591,"took":"136.485441ms","hash":336327719,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":30887936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T12:11:55.289371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":336327719,"revision":30591,"compact-revision":27991} {"level":"info","ts":"2026-08-11T12:16:55.156027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32845} {"level":"info","ts":"2026-08-11T12:16:55.313004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32845,"took":"151.012352ms","hash":466579161,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":30519296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T12:16:55.313123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":466579161,"revision":32845,"compact-revision":30591} {"level":"info","ts":"2026-08-11T12:21:55.161864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35076} {"level":"info","ts":"2026-08-11T12:21:55.316487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35076,"took":"150.866059ms","hash":2314227869,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T12:21:55.316545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2314227869,"revision":35076,"compact-revision":32845} {"level":"info","ts":"2026-08-11T12:25:01.028757Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T12:25:01.033091Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-08-11T12:25:01.033185Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-08-11T12:26:55.171636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37369} {"level":"info","ts":"2026-08-11T12:26:55.298682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37369,"took":"122.786898ms","hash":3493899263,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":33067008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-11T12:26:55.298745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3493899263,"revision":37369,"compact-revision":35076} {"level":"info","ts":"2026-08-11T12:31:55.178139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39649} {"level":"info","ts":"2026-08-11T12:31:55.309218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39649,"took":"127.366271ms","hash":1611591364,"current-db-size-bytes":60370944,"current-db-size":"60 MB","current-db-size-in-use-bytes":30502912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T12:31:55.309306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1611591364,"revision":39649,"compact-revision":37369}