{"level":"warn","ts":"2026-04-02T08:49:40.482748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:49:40.482894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-04-02T08:49:40.483759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:49:40.483774Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-02T08:49:40.483808Z","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-04-02T08:49:40.484872Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-02T08:49:40.484984Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-04-02T08:49:40.490558Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.302167ms"} {"level":"info","ts":"2026-04-02T08:49:40.498105Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-02T08:49:40.498275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:49:40.498423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-02T08:49:40.498455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:49:40.498465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-02T08:49:40.498517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-02T08:49:40.504802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:49:40.506299Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:49:40.508199Z","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-04-02T08:49:40.51112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:49:40.511582Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:49:40.511608Z","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-04-02T08:49:40.511771Z","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-04-02T08:49:40.511793Z","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-04-02T08:49:40.515978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-02T08:49:40.516201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-02T08:49:40.51791Z","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-04-02T08:49:40.518026Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-02T08:49:40.518084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-02T08:49:40.518536Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:49:40.518606Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:49:40.799094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:49:40.799186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:49:40.799298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-02T08:49:40.799333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:49:40.799354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-02T08:49:40.799391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-02T08:49:40.799414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-02T08:49:40.802031Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:49:40.802037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:49:40.802037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:49:40.802195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:49:40.802411Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:49:40.802499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:49:40.803143Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:49:40.803281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:49:40.803342Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:49:40.804951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:49:40.806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-02T08:49:58.577619Z","caller":"traceutil/trace.go:171","msg":"trace[391511962] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"111.563376ms","start":"2026-04-02T08:49:58.466021Z","end":"2026-04-02T08:49:58.577584Z","steps":["trace[391511962] 'process raft request' (duration: 111.35895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:58.931021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.889846ms","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-04-02T08:49:58.93121Z","caller":"traceutil/trace.go:171","msg":"trace[372889948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"171.134783ms","start":"2026-04-02T08:49:58.760047Z","end":"2026-04-02T08:49:58.931182Z","steps":["trace[372889948] 'range keys from in-memory index tree' (duration: 170.70595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:18.223649Z","caller":"traceutil/trace.go:171","msg":"trace[531051712] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"135.205849ms","start":"2026-04-02T08:50:18.088416Z","end":"2026-04-02T08:50:18.223621Z","steps":["trace[531051712] 'process raft request' (duration: 135.004063ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:18.465451Z","caller":"traceutil/trace.go:171","msg":"trace[1456064202] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"130.24392ms","start":"2026-04-02T08:50:18.335147Z","end":"2026-04-02T08:50:18.465391Z","steps":["trace[1456064202] 'process raft request' (duration: 129.499749ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:18.668887Z","caller":"traceutil/trace.go:171","msg":"trace[68020601] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"146.781271ms","start":"2026-04-02T08:50:18.522013Z","end":"2026-04-02T08:50:18.668795Z","steps":["trace[68020601] 'process raft request' (duration: 132.798281ms)","trace[68020601] 'compare' (duration: 13.817025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:50:19.185788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.952266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T08:50:19.185927Z","caller":"traceutil/trace.go:171","msg":"trace[861180469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"411.168622ms","start":"2026-04-02T08:50:18.774738Z","end":"2026-04-02T08:50:19.185907Z","steps":["trace[861180469] 'range keys from in-memory index tree' (duration: 410.72209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:50:19.185941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.429314ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:50:19.186038Z","caller":"traceutil/trace.go:171","msg":"trace[19227891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"352.529277ms","start":"2026-04-02T08:50:18.833488Z","end":"2026-04-02T08:50:19.186018Z","steps":["trace[19227891] 'range keys from in-memory index tree' (duration: 352.416334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:50:19.185979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:50:18.774712Z","time spent":"411.254094ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T08:50:19.192864Z","caller":"traceutil/trace.go:171","msg":"trace[2071519622] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"140.518757ms","start":"2026-04-02T08:50:19.052307Z","end":"2026-04-02T08:50:19.192826Z","steps":["trace[2071519622] 'read index received' (duration: 118.093382ms)","trace[2071519622] 'applied index is now lower than readState.Index' (duration: 22.420295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:50:19.193293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.967879ms","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-04-02T08:50:19.193469Z","caller":"traceutil/trace.go:171","msg":"trace[397600805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"141.206796ms","start":"2026-04-02T08:50:19.052231Z","end":"2026-04-02T08:50:19.193438Z","steps":["trace[397600805] 'agreement among raft nodes before linearized reading' (duration: 140.838756ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:19.193358Z","caller":"traceutil/trace.go:171","msg":"trace[2122262069] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"111.271971ms","start":"2026-04-02T08:50:19.082067Z","end":"2026-04-02T08:50:19.193339Z","steps":["trace[2122262069] 'process raft request' (duration: 110.741707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:50:19.194379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.621495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:50:19.194463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.285055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:50:19.194507Z","caller":"traceutil/trace.go:171","msg":"trace[768382987] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"119.369298ms","start":"2026-04-02T08:50:19.075126Z","end":"2026-04-02T08:50:19.194496Z","steps":["trace[768382987] 'agreement among raft nodes before linearized reading' (duration: 119.254375ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:19.194495Z","caller":"traceutil/trace.go:171","msg":"trace[1118191291] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"119.80893ms","start":"2026-04-02T08:50:19.07466Z","end":"2026-04-02T08:50:19.194469Z","steps":["trace[1118191291] 'agreement among raft nodes before linearized reading' (duration: 119.599785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:50:19.194588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.860281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:50:19.194642Z","caller":"traceutil/trace.go:171","msg":"trace[65741153] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:582; }","duration":"119.950894ms","start":"2026-04-02T08:50:19.074676Z","end":"2026-04-02T08:50:19.194627Z","steps":["trace[65741153] 'agreement among raft nodes before linearized reading' (duration: 119.882292ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:34.751717Z","caller":"traceutil/trace.go:171","msg":"trace[1237859575] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"155.618367ms","start":"2026-04-02T08:50:34.596066Z","end":"2026-04-02T08:50:34.751684Z","steps":["trace[1237859575] 'process raft request' (duration: 155.36407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:09.873332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.393484ms","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-04-02T08:51:09.873434Z","caller":"traceutil/trace.go:171","msg":"trace[2105795496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"111.553808ms","start":"2026-04-02T08:51:09.76186Z","end":"2026-04-02T08:51:09.873414Z","steps":["trace[2105795496] 'range keys from in-memory index tree' (duration: 111.150388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:57.23027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.509508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9da70581-4ed7-422f-912c-06479b2ca58a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-02T08:51:57.230369Z","caller":"traceutil/trace.go:171","msg":"trace[2116765769] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9da70581-4ed7-422f-912c-06479b2ca58a; range_end:; response_count:1; response_revision:1832; }","duration":"133.656493ms","start":"2026-04-02T08:51:57.096693Z","end":"2026-04-02T08:51:57.230349Z","steps":["trace[2116765769] 'range keys from in-memory index tree' (duration: 133.300582ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:52:10.262589Z","caller":"traceutil/trace.go:171","msg":"trace[64184494] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"115.614675ms","start":"2026-04-02T08:52:10.146938Z","end":"2026-04-02T08:52:10.262553Z","steps":["trace[64184494] 'process raft request' (duration: 115.364018ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:52:14.052938Z","caller":"traceutil/trace.go:171","msg":"trace[1401824975] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"155.846825ms","start":"2026-04-02T08:52:13.89705Z","end":"2026-04-02T08:52:14.052897Z","steps":["trace[1401824975] 'process raft request' (duration: 133.389214ms)","trace[1401824975] 'compare' (duration: 22.125491ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:52:46.911316Z","caller":"traceutil/trace.go:171","msg":"trace[1484969555] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"106.38694ms","start":"2026-04-02T08:52:46.804903Z","end":"2026-04-02T08:52:46.91129Z","steps":["trace[1484969555] 'process raft request' (duration: 106.215375ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:54:00.725156Z","caller":"traceutil/trace.go:171","msg":"trace[1409562969] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"109.587514ms","start":"2026-04-02T08:54:00.615536Z","end":"2026-04-02T08:54:00.725123Z","steps":["trace[1409562969] 'process raft request' (duration: 104.716907ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:54:01.132718Z","caller":"traceutil/trace.go:171","msg":"trace[389423213] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"211.411538ms","start":"2026-04-02T08:54:00.921275Z","end":"2026-04-02T08:54:01.132686Z","steps":["trace[389423213] 'process raft request' (duration: 211.09327ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:55:49.614963Z","caller":"traceutil/trace.go:171","msg":"trace[1790342154] linearizableReadLoop","detail":"{readStateIndex:3458; appliedIndex:3457; }","duration":"140.480576ms","start":"2026-04-02T08:55:49.474442Z","end":"2026-04-02T08:55:49.614922Z","steps":["trace[1790342154] 'read index received' (duration: 54.947297ms)","trace[1790342154] 'applied index is now lower than readState.Index' (duration: 85.528409ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:55:49.614989Z","caller":"traceutil/trace.go:171","msg":"trace[105061991] transaction","detail":"{read_only:false; response_revision:3325; number_of_response:1; }","duration":"131.076621ms","start":"2026-04-02T08:55:49.483887Z","end":"2026-04-02T08:55:49.614964Z","steps":["trace[105061991] 'process raft request' (duration: 130.943938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:55:49.615186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.724224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:8141"} {"level":"info","ts":"2026-04-02T08:55:49.615223Z","caller":"traceutil/trace.go:171","msg":"trace[1593241787] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:3325; }","duration":"140.802965ms","start":"2026-04-02T08:55:49.47441Z","end":"2026-04-02T08:55:49.615213Z","steps":["trace[1593241787] 'agreement among raft nodes before linearized reading' (duration: 140.63149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:36.525037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.416585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-02T08:56:36.525142Z","caller":"traceutil/trace.go:171","msg":"trace[787510813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3768; }","duration":"340.563869ms","start":"2026-04-02T08:56:36.184554Z","end":"2026-04-02T08:56:36.525118Z","steps":["trace[787510813] 'range keys from in-memory index tree' (duration: 340.25742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:36.525186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:36.184536Z","time spent":"340.639521ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-02T08:58:01.325743Z","caller":"traceutil/trace.go:171","msg":"trace[656661529] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"106.426993ms","start":"2026-04-02T08:58:01.219241Z","end":"2026-04-02T08:58:01.325668Z","steps":["trace[656661529] 'process raft request' (duration: 106.246177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:58:05.494616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.645024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:58:05.49473Z","caller":"traceutil/trace.go:171","msg":"trace[448690264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5060; }","duration":"103.786578ms","start":"2026-04-02T08:58:05.390919Z","end":"2026-04-02T08:58:05.494705Z","steps":["trace[448690264] 'agreement among raft nodes before linearized reading' (duration: 103.604742ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:58:10.665021Z","caller":"traceutil/trace.go:171","msg":"trace[326321537] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"102.462481ms","start":"2026-04-02T08:58:10.562516Z","end":"2026-04-02T08:58:10.664979Z","steps":["trace[326321537] 'compare' (duration: 92.79472ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:58:10.665219Z","caller":"traceutil/trace.go:171","msg":"trace[1826771747] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"101.641728ms","start":"2026-04-02T08:58:10.563538Z","end":"2026-04-02T08:58:10.665179Z","steps":["trace[1826771747] 'process raft request' (duration: 101.37139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:58:19.157684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.368345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:58:19.15789Z","caller":"traceutil/trace.go:171","msg":"trace[942355469] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"263.327663ms","start":"2026-04-02T08:58:18.894518Z","end":"2026-04-02T08:58:19.157846Z","steps":["trace[942355469] 'process raft request' (duration: 127.191016ms)","trace[942355469] 'compare' (duration: 135.233242ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:58:19.157914Z","caller":"traceutil/trace.go:171","msg":"trace[1873356244] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5537; }","duration":"259.959868ms","start":"2026-04-02T08:58:18.897924Z","end":"2026-04-02T08:58:19.157884Z","steps":["trace[1873356244] 'read index received' (duration: 8.871279ms)","trace[1873356244] 'applied index is now lower than readState.Index' (duration: 251.086099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:58:19.449257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.424603ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T08:58:19.44935Z","caller":"traceutil/trace.go:171","msg":"trace[1620898745] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"291.238038ms","start":"2026-04-02T08:58:19.158095Z","end":"2026-04-02T08:58:19.449333Z","steps":["trace[1620898745] 'read index received' (duration: 80.052µs)","trace[1620898745] 'applied index is now lower than readState.Index' (duration: 291.156316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:58:19.44956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.742096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-02T08:58:19.449619Z","caller":"traceutil/trace.go:171","msg":"trace[798676593] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5337; }","duration":"342.812498ms","start":"2026-04-02T08:58:19.106773Z","end":"2026-04-02T08:58:19.449586Z","steps":["trace[798676593] 'agreement among raft nodes before linearized reading' (duration: 342.660633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:58:19.449657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:58:19.106751Z","time spent":"342.897559ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T08:58:19.449714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.639153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8074654c-eef9-4bf6-a28c-a95c882ca46b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-02T08:58:19.449791Z","caller":"traceutil/trace.go:171","msg":"trace[2028132537] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8074654c-eef9-4bf6-a28c-a95c882ca46b; range_end:; response_count:1; response_revision:5337; }","duration":"469.748156ms","start":"2026-04-02T08:58:18.980012Z","end":"2026-04-02T08:58:19.44976Z","steps":["trace[2028132537] 'agreement among raft nodes before linearized reading' (duration: 469.567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:58:19.449825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:58:18.979993Z","time spent":"469.824879ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8074654c-eef9-4bf6-a28c-a95c882ca46b\" "} {"level":"warn","ts":"2026-04-02T08:58:19.450023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.122806ms","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-04-02T08:58:19.450048Z","caller":"traceutil/trace.go:171","msg":"trace[406818069] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5337; }","duration":"232.192288ms","start":"2026-04-02T08:58:19.217849Z","end":"2026-04-02T08:58:19.450041Z","steps":["trace[406818069] 'agreement among raft nodes before linearized reading' (duration: 232.124026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:58:19.45003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.573986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-02T08:58:19.450233Z","caller":"traceutil/trace.go:171","msg":"trace[250442674] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5337; }","duration":"552.278387ms","start":"2026-04-02T08:58:18.897878Z","end":"2026-04-02T08:58:19.450156Z","steps":["trace[250442674] 'agreement among raft nodes before linearized reading' (duration: 260.254757ms)","trace[250442674] 'range keys from in-memory index tree' (duration: 291.206837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:58:19.450287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:58:18.89786Z","time spent":"552.412112ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-04-02T08:58:28.834403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.383012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:58:28.835322Z","caller":"traceutil/trace.go:171","msg":"trace[1213401175] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"148.930408ms","start":"2026-04-02T08:58:28.68562Z","end":"2026-04-02T08:58:28.83455Z","steps":["trace[1213401175] 'process raft request' (duration: 11.316968ms)","trace[1213401175] 'compare' (duration: 137.247429ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:59:41.003879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2968} {"level":"info","ts":"2026-04-02T08:59:41.073406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2968,"took":"68.129486ms","hash":847314784,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":33951744,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T08:59:41.073464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":847314784,"revision":2968,"compact-revision":-1} {"level":"info","ts":"2026-04-02T09:02:01.196793Z","caller":"traceutil/trace.go:171","msg":"trace[1521037586] linearizableReadLoop","detail":"{readStateIndex:7230; appliedIndex:7229; }","duration":"331.579429ms","start":"2026-04-02T09:02:00.865179Z","end":"2026-04-02T09:02:01.196758Z","steps":["trace[1521037586] 'read index received' (duration: 331.446995ms)","trace[1521037586] 'applied index is now lower than readState.Index' (duration: 130.764µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:02:01.196858Z","caller":"traceutil/trace.go:171","msg":"trace[1710042597] transaction","detail":"{read_only:false; response_revision:6977; number_of_response:1; }","duration":"389.503378ms","start":"2026-04-02T09:02:00.807318Z","end":"2026-04-02T09:02:01.196821Z","steps":["trace[1710042597] 'process raft request' (duration: 389.260801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.197036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:00.807287Z","time spent":"389.650262ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:01.197186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.9896ms","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-04-02T09:02:01.197246Z","caller":"traceutil/trace.go:171","msg":"trace[1815028419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6977; }","duration":"332.102213ms","start":"2026-04-02T09:02:00.865131Z","end":"2026-04-02T09:02:01.197233Z","steps":["trace[1815028419] 'agreement among raft nodes before linearized reading' (duration: 331.773294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.197369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:00.865117Z","time spent":"332.238536ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T09:02:01.460912Z","caller":"traceutil/trace.go:171","msg":"trace[711838879] transaction","detail":"{read_only:false; response_revision:6979; number_of_response:1; }","duration":"258.80868ms","start":"2026-04-02T09:02:01.202076Z","end":"2026-04-02T09:02:01.460885Z","steps":["trace[711838879] 'process raft request' (duration: 258.747558ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:01.460934Z","caller":"traceutil/trace.go:171","msg":"trace[450314820] linearizableReadLoop","detail":"{readStateIndex:7231; appliedIndex:7230; }","duration":"264.035348ms","start":"2026-04-02T09:02:01.196881Z","end":"2026-04-02T09:02:01.460916Z","steps":["trace[450314820] 'read index received' (duration: 261.425084ms)","trace[450314820] 'applied index is now lower than readState.Index' (duration: 2.608514ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:02:01.460934Z","caller":"traceutil/trace.go:171","msg":"trace[480646030] transaction","detail":"{read_only:false; response_revision:6978; number_of_response:1; }","duration":"486.519817ms","start":"2026-04-02T09:02:00.974397Z","end":"2026-04-02T09:02:01.460917Z","steps":["trace[480646030] 'process raft request' (duration: 483.896643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.463689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:00.974381Z","time spent":"489.235674ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:01.463698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.679787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-04-02T09:02:01.461027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.300721ms","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-04-02T09:02:01.463866Z","caller":"traceutil/trace.go:171","msg":"trace[437365540] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa; range_end:; response_count:1; response_revision:6979; }","duration":"103.821021ms","start":"2026-04-02T09:02:01.359968Z","end":"2026-04-02T09:02:01.463789Z","steps":["trace[437365540] 'agreement among raft nodes before linearized reading' (duration: 103.672456ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:01.463866Z","caller":"traceutil/trace.go:171","msg":"trace[1215737341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6979; }","duration":"569.12087ms","start":"2026-04-02T09:02:00.894691Z","end":"2026-04-02T09:02:01.463812Z","steps":["trace[1215737341] 'agreement among raft nodes before linearized reading' (duration: 566.26575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.464042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:00.894683Z","time spent":"569.275564ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:03.017349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948813047,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:03.517751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948813047,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:04.017959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948813047,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:04.343859Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.961632394s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T09:02:04.344452Z","caller":"traceutil/trace.go:171","msg":"trace[364587584] transaction","detail":"{read_only:false; response_revision:6983; number_of_response:1; }","duration":"1.962294882s","start":"2026-04-02T09:02:02.382135Z","end":"2026-04-02T09:02:04.344429Z","steps":["trace[364587584] 'process raft request' (duration: 1.962008734s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:04.344593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.382112Z","time spent":"1.962387025s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:05.390157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99987161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T09:02:05.390244Z","caller":"traceutil/trace.go:171","msg":"trace[1460028700] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000025484s","start":"2026-04-02T09:02:03.390203Z","end":"2026-04-02T09:02:05.390229Z","steps":["trace[1460028700] 'agreement among raft nodes before linearized reading' (duration: 1.999907071s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.390283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.390182Z","time spent":"2.000092517s","remote":"127.0.0.1:60076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T09:02:05.40013Z","caller":"traceutil/trace.go:171","msg":"trace[269781309] transaction","detail":"{read_only:false; response_revision:6984; number_of_response:1; }","duration":"2.716379257s","start":"2026-04-02T09:02:02.683713Z","end":"2026-04-02T09:02:05.400093Z","steps":["trace[269781309] 'process raft request' (duration: 2.7147085s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:05.40015Z","caller":"traceutil/trace.go:171","msg":"trace[1459275139] linearizableReadLoop","detail":"{readStateIndex:7238; appliedIndex:7235; }","duration":"2.882957433s","start":"2026-04-02T09:02:02.517165Z","end":"2026-04-02T09:02:05.400122Z","steps":["trace[1459275139] 'read index received' (duration: 1.826934985s)","trace[1459275139] 'applied index is now lower than readState.Index' (duration: 1.056021288s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:02:05.40027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.683679Z","time spent":"2.716519971s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:05.400251Z","caller":"traceutil/trace.go:171","msg":"trace[894660121] transaction","detail":"{read_only:false; response_revision:6985; number_of_response:1; }","duration":"1.932744682s","start":"2026-04-02T09:02:03.467479Z","end":"2026-04-02T09:02:05.400224Z","steps":["trace[894660121] 'process raft request' (duration: 1.932519026s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.40036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.883192179s","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-04-02T09:02:05.400375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.467453Z","time spent":"1.932856385s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:05.400398Z","caller":"traceutil/trace.go:171","msg":"trace[1353135657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6985; }","duration":"2.883279673s","start":"2026-04-02T09:02:02.517106Z","end":"2026-04-02T09:02:05.400386Z","steps":["trace[1353135657] 'agreement among raft nodes before linearized reading' (duration: 2.883138789s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.40043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.517086Z","time spent":"2.883336214s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T09:02:05.400547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.858600788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-04-02T09:02:05.400572Z","caller":"traceutil/trace.go:171","msg":"trace[881935751] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa; range_end:; response_count:1; response_revision:6985; }","duration":"2.858647449s","start":"2026-04-02T09:02:02.541916Z","end":"2026-04-02T09:02:05.400564Z","steps":["trace[881935751] 'agreement among raft nodes before linearized reading' (duration: 2.858549026s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.400596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.541904Z","time spent":"2.85868677s","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3971,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa\" "} {"level":"warn","ts":"2026-04-02T09:02:05.400629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539856698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-barbican-server-0.18a27eb51ccdeed2\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-02T09:02:05.40066Z","caller":"traceutil/trace.go:171","msg":"trace[882046787] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-barbican-server-0.18a27eb51ccdeed2; range_end:; response_count:1; response_revision:6985; }","duration":"1.539930911s","start":"2026-04-02T09:02:03.86072Z","end":"2026-04-02T09:02:05.40065Z","steps":["trace[882046787] 'agreement among raft nodes before linearized reading' (duration: 1.539821378s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.400681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.860695Z","time spent":"1.539980682s","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1034,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-barbican-server-0.18a27eb51ccdeed2\" "} {"level":"warn","ts":"2026-04-02T09:02:05.400744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.804344739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2dj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T09:02:05.400765Z","caller":"traceutil/trace.go:171","msg":"trace[1087180555] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2dj4; range_end:; response_count:1; response_revision:6985; }","duration":"1.804376251s","start":"2026-04-02T09:02:03.596382Z","end":"2026-04-02T09:02:05.400759Z","steps":["trace[1087180555] 'agreement among raft nodes before linearized reading' (duration: 1.804311309s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.400785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.596377Z","time spent":"1.804401841s","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-z2dj4\" "} {"level":"warn","ts":"2026-04-02T09:02:05.400877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.672101662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-02T09:02:05.4009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.48501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:02:05.400908Z","caller":"traceutil/trace.go:171","msg":"trace[918721520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6985; }","duration":"2.672165104s","start":"2026-04-02T09:02:02.728734Z","end":"2026-04-02T09:02:05.400899Z","steps":["trace[918721520] 'agreement among raft nodes before linearized reading' (duration: 2.672066571s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:05.40093Z","caller":"traceutil/trace.go:171","msg":"trace[1616248402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6985; }","duration":"425.548631ms","start":"2026-04-02T09:02:04.975373Z","end":"2026-04-02T09:02:05.400921Z","steps":["trace[1616248402] 'agreement among raft nodes before linearized reading' (duration: 425.49952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.400936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.728722Z","time spent":"2.672207655s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:05.400952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.975355Z","time spent":"425.590563ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.400969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.760399005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414319"} {"level":"info","ts":"2026-04-02T09:02:05.400991Z","caller":"traceutil/trace.go:171","msg":"trace[1962568720] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:6985; }","duration":"2.760471247s","start":"2026-04-02T09:02:02.640514Z","end":"2026-04-02T09:02:05.400985Z","steps":["trace[1962568720] 'agreement among raft nodes before linearized reading' (duration: 2.760346374s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.401009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.640489Z","time spent":"2.760515949s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":414341,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-02T09:02:05.401093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.691114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T09:02:05.401094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812400086s","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-04-02T09:02:05.401116Z","caller":"traceutil/trace.go:171","msg":"trace[1060472214] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6985; }","duration":"565.750906ms","start":"2026-04-02T09:02:04.835359Z","end":"2026-04-02T09:02:05.40111Z","steps":["trace[1060472214] 'agreement among raft nodes before linearized reading' (duration: 565.707474ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:05.401127Z","caller":"traceutil/trace.go:171","msg":"trace[1939419130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6985; }","duration":"1.812484819s","start":"2026-04-02T09:02:03.588634Z","end":"2026-04-02T09:02:05.401118Z","steps":["trace[1939419130] 'agreement among raft nodes before linearized reading' (duration: 1.812402977s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.401137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.83534Z","time spent":"565.791377ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.401151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.58861Z","time spent":"1.81253609s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:05.401296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060110003s","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:7"} {"level":"info","ts":"2026-04-02T09:02:05.401362Z","caller":"traceutil/trace.go:171","msg":"trace[1101225151] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:6985; }","duration":"1.060175755s","start":"2026-04-02T09:02:04.341178Z","end":"2026-04-02T09:02:05.401354Z","steps":["trace[1101225151] 'agreement among raft nodes before linearized reading' (duration: 1.060050131s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.401384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.341115Z","time spent":"1.060261158s","remote":"127.0.0.1:60112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":73,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.401563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102310511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-02T09:02:05.401589Z","caller":"traceutil/trace.go:171","msg":"trace[1322850632] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican; range_end:; response_count:1; response_revision:6985; }","duration":"1.102373933s","start":"2026-04-02T09:02:04.299209Z","end":"2026-04-02T09:02:05.401582Z","steps":["trace[1322850632] 'agreement among raft nodes before linearized reading' (duration: 1.102301731s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.401608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.29919Z","time spent":"1.102412153s","remote":"127.0.0.1:54340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" "} {"level":"warn","ts":"2026-04-02T09:02:05.401783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321191148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T09:02:05.401807Z","caller":"traceutil/trace.go:171","msg":"trace[160833664] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:6985; }","duration":"1.321279031s","start":"2026-04-02T09:02:04.080522Z","end":"2026-04-02T09:02:05.401801Z","steps":["trace[160833664] 'agreement among raft nodes before linearized reading' (duration: 1.321198829s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.40183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.0805Z","time spent":"1.321321191s","remote":"127.0.0.1:60096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-04-02T09:02:05.402058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.799086584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T09:02:05.402098Z","caller":"traceutil/trace.go:171","msg":"trace[997043044] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6985; }","duration":"2.799160716s","start":"2026-04-02T09:02:02.602927Z","end":"2026-04-02T09:02:05.402087Z","steps":["trace[997043044] 'agreement among raft nodes before linearized reading' (duration: 2.799053863s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.40212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:02.602907Z","time spent":"2.799208527s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T09:02:05.404192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925190331s","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-04-02T09:02:05.404286Z","caller":"traceutil/trace.go:171","msg":"trace[659798742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6985; }","duration":"1.925320714s","start":"2026-04-02T09:02:03.478942Z","end":"2026-04-02T09:02:05.404262Z","steps":["trace[659798742] 'agreement among raft nodes before linearized reading' (duration: 1.923641837s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.404332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.478926Z","time spent":"1.925397336s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:05.404548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446771291s","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-04-02T09:02:05.404607Z","caller":"traceutil/trace.go:171","msg":"trace[1879979451] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6985; }","duration":"1.446870063s","start":"2026-04-02T09:02:03.957723Z","end":"2026-04-02T09:02:05.404593Z","steps":["trace[1879979451] 'agreement among raft nodes before linearized reading' (duration: 1.444933279s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.404638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.957703Z","time spent":"1.446927145s","remote":"127.0.0.1:60250","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":"warn","ts":"2026-04-02T09:02:05.405025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938534785s","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-04-02T09:02:05.405067Z","caller":"traceutil/trace.go:171","msg":"trace[1756384163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6985; }","duration":"1.938594456s","start":"2026-04-02T09:02:03.466464Z","end":"2026-04-02T09:02:05.405058Z","steps":["trace[1756384163] 'agreement among raft nodes before linearized reading' (duration: 1.938434532s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.405087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.466421Z","time spent":"1.938662248s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T09:02:06.612867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948813079,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T09:02:06.804256Z","caller":"traceutil/trace.go:171","msg":"trace[507659244] transaction","detail":"{read_only:false; response_revision:6997; number_of_response:1; }","duration":"844.97716ms","start":"2026-04-02T09:02:05.959251Z","end":"2026-04-02T09:02:06.804228Z","steps":["trace[507659244] 'process raft request' (duration: 844.764054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.804495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:05.959236Z","time spent":"845.123324ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3936,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:06.808194Z","caller":"traceutil/trace.go:171","msg":"trace[1210331006] transaction","detail":"{read_only:false; response_revision:6998; number_of_response:1; }","duration":"521.541852ms","start":"2026-04-02T09:02:06.286627Z","end":"2026-04-02T09:02:06.808169Z","steps":["trace[1210331006] 'process raft request' (duration: 521.298775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.808366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.286611Z","time spent":"521.659186ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:06.808399Z","caller":"traceutil/trace.go:171","msg":"trace[744237851] linearizableReadLoop","detail":"{readStateIndex:7253; appliedIndex:7250; }","duration":"695.846087ms","start":"2026-04-02T09:02:06.112532Z","end":"2026-04-02T09:02:06.808378Z","steps":["trace[744237851] 'read index received' (duration: 691.498344ms)","trace[744237851] 'applied index is now lower than readState.Index' (duration: 4.346593ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:02:06.80849Z","caller":"traceutil/trace.go:171","msg":"trace[1831282591] transaction","detail":"{read_only:false; response_revision:6999; number_of_response:1; }","duration":"456.748689ms","start":"2026-04-02T09:02:06.351731Z","end":"2026-04-02T09:02:06.80848Z","steps":["trace[1831282591] 'process raft request' (duration: 456.333258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.808555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.351696Z","time spent":"456.814241ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:06.808694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.864195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:02:06.808771Z","caller":"traceutil/trace.go:171","msg":"trace[1220654135] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:6999; }","duration":"567.992149ms","start":"2026-04-02T09:02:06.24076Z","end":"2026-04-02T09:02:06.808752Z","steps":["trace[1220654135] 'agreement among raft nodes before linearized reading' (duration: 567.848135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.808812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.240743Z","time spent":"568.057861ms","remote":"127.0.0.1:39192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":29,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:06.808844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.326399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19521"} {"level":"info","ts":"2026-04-02T09:02:06.808877Z","caller":"traceutil/trace.go:171","msg":"trace[1702786263] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6999; }","duration":"696.410902ms","start":"2026-04-02T09:02:06.112456Z","end":"2026-04-02T09:02:06.808867Z","steps":["trace[1702786263] 'agreement among raft nodes before linearized reading' (duration: 696.286809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.808909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.112437Z","time spent":"696.467013ms","remote":"127.0.0.1:60234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19543,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T09:02:06.808718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.240776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:06.80905Z","caller":"traceutil/trace.go:171","msg":"trace[28512703] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:6999; }","duration":"252.603276ms","start":"2026-04-02T09:02:06.556428Z","end":"2026-04-02T09:02:06.809031Z","steps":["trace[28512703] 'agreement among raft nodes before linearized reading' (duration: 252.246236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.809072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.867534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:06.809185Z","caller":"traceutil/trace.go:171","msg":"trace[1679296181] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:6999; }","duration":"488.050179ms","start":"2026-04-02T09:02:06.321109Z","end":"2026-04-02T09:02:06.809159Z","steps":["trace[1679296181] 'agreement among raft nodes before linearized reading' (duration: 487.841433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.809218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.321089Z","time spent":"488.121072ms","remote":"127.0.0.1:60394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:06.809103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.298825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-04-02T09:02:06.809281Z","caller":"traceutil/trace.go:171","msg":"trace[2048849390] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa; range_end:; response_count:1; response_revision:6999; }","duration":"398.529351ms","start":"2026-04-02T09:02:06.410735Z","end":"2026-04-02T09:02:06.809264Z","steps":["trace[2048849390] 'agreement among raft nodes before linearized reading' (duration: 398.288174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:06.809331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:06.410699Z","time spent":"398.615644ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3973,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d87eab25-a7a8-488f-bf5d-26e4a428e1fa\" "} {"level":"info","ts":"2026-04-02T09:04:40.99046Z","caller":"traceutil/trace.go:171","msg":"trace[1889866592] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"107.250908ms","start":"2026-04-02T09:04:40.88317Z","end":"2026-04-02T09:04:40.990421Z","steps":["trace[1889866592] 'process raft request' (duration: 106.904747ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:41.085969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6080} {"level":"info","ts":"2026-04-02T09:04:41.182671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6080,"took":"94.681194ms","hash":4107552381,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":23019520,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-02T09:04:41.182746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107552381,"revision":6080,"compact-revision":2968} {"level":"info","ts":"2026-04-02T09:04:52.449193Z","caller":"traceutil/trace.go:171","msg":"trace[714726725] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"138.903478ms","start":"2026-04-02T09:04:52.310267Z","end":"2026-04-02T09:04:52.44917Z","steps":["trace[714726725] 'process raft request' (duration: 121.335614ms)","trace[714726725] 'compare' (duration: 17.469271ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:52.474856Z","caller":"traceutil/trace.go:171","msg":"trace[1267807779] linearizableReadLoop","detail":"{readStateIndex:8596; appliedIndex:8593; }","duration":"162.023558ms","start":"2026-04-02T09:04:52.312809Z","end":"2026-04-02T09:04:52.474832Z","steps":["trace[1267807779] 'read index received' (duration: 118.808053ms)","trace[1267807779] 'applied index is now lower than readState.Index' (duration: 43.214505ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:52.474969Z","caller":"traceutil/trace.go:171","msg":"trace[686221182] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"162.80238ms","start":"2026-04-02T09:04:52.312156Z","end":"2026-04-02T09:04:52.474958Z","steps":["trace[686221182] 'process raft request' (duration: 162.634055ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:52.474929Z","caller":"traceutil/trace.go:171","msg":"trace[2046085047] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"163.997033ms","start":"2026-04-02T09:04:52.310891Z","end":"2026-04-02T09:04:52.474888Z","steps":["trace[2046085047] 'process raft request' (duration: 163.761116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:52.475209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.820426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2dj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T09:04:52.475297Z","caller":"traceutil/trace.go:171","msg":"trace[983928608] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2dj4; range_end:; response_count:1; response_revision:8301; }","duration":"111.97995ms","start":"2026-04-02T09:04:52.363299Z","end":"2026-04-02T09:04:52.475279Z","steps":["trace[983928608] 'agreement among raft nodes before linearized reading' (duration: 111.721043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:52.475294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.45638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T09:04:52.475478Z","caller":"traceutil/trace.go:171","msg":"trace[2072233296] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8301; }","duration":"162.668676ms","start":"2026-04-02T09:04:52.312789Z","end":"2026-04-02T09:04:52.475458Z","steps":["trace[2072233296] 'agreement among raft nodes before linearized reading' (duration: 162.334897ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:54.138593Z","caller":"traceutil/trace.go:171","msg":"trace[1988220510] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"109.716346ms","start":"2026-04-02T09:04:54.028838Z","end":"2026-04-02T09:04:54.138554Z","steps":["trace[1988220510] 'process raft request' (duration: 109.518411ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:54.24817Z","caller":"traceutil/trace.go:171","msg":"trace[617307346] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"106.895667ms","start":"2026-04-02T09:04:54.141245Z","end":"2026-04-02T09:04:54.248141Z","steps":["trace[617307346] 'process raft request' (duration: 106.692681ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:54.807001Z","caller":"traceutil/trace.go:171","msg":"trace[1670014386] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8606; }","duration":"263.4268ms","start":"2026-04-02T09:04:54.543529Z","end":"2026-04-02T09:04:54.806956Z","steps":["trace[1670014386] 'read index received' (duration: 13.91638ms)","trace[1670014386] 'applied index is now lower than readState.Index' (duration: 249.50935ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:54.807185Z","caller":"traceutil/trace.go:171","msg":"trace[1704260774] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"263.282587ms","start":"2026-04-02T09:04:54.54387Z","end":"2026-04-02T09:04:54.807153Z","steps":["trace[1704260774] 'process raft request' (duration: 263.02859ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:54.80749Z","caller":"traceutil/trace.go:171","msg":"trace[1627972848] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"264.201083ms","start":"2026-04-02T09:04:54.543268Z","end":"2026-04-02T09:04:54.807469Z","steps":["trace[1627972848] 'process raft request' (duration: 214.080233ms)","trace[1627972848] 'compare' (duration: 49.337198ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:54.807647Z","caller":"traceutil/trace.go:171","msg":"trace[1717432497] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"264.151482ms","start":"2026-04-02T09:04:54.543484Z","end":"2026-04-02T09:04:54.807636Z","steps":["trace[1717432497] 'process raft request' (duration: 263.349569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:54.807729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.173742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223869"} {"level":"info","ts":"2026-04-02T09:04:54.807808Z","caller":"traceutil/trace.go:171","msg":"trace[203403089] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8314; }","duration":"264.291255ms","start":"2026-04-02T09:04:54.543499Z","end":"2026-04-02T09:04:54.80779Z","steps":["trace[203403089] 'agreement among raft nodes before linearized reading' (duration: 263.828933ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.602218Z","caller":"traceutil/trace.go:171","msg":"trace[1619637777] linearizableReadLoop","detail":"{readStateIndex:8817; appliedIndex:8816; }","duration":"100.709064ms","start":"2026-04-02T09:05:22.501486Z","end":"2026-04-02T09:05:22.602195Z","steps":["trace[1619637777] 'read index received' (duration: 100.515959ms)","trace[1619637777] 'applied index is now lower than readState.Index' (duration: 192.285µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:05:22.60243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.931312ms","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-04-02T09:05:22.60247Z","caller":"traceutil/trace.go:171","msg":"trace[893355853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8513; }","duration":"101.016414ms","start":"2026-04-02T09:05:22.501442Z","end":"2026-04-02T09:05:22.602459Z","steps":["trace[893355853] 'agreement among raft nodes before linearized reading' (duration: 100.85182ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.602729Z","caller":"traceutil/trace.go:171","msg":"trace[1253262942] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"125.300863ms","start":"2026-04-02T09:05:22.477418Z","end":"2026-04-02T09:05:22.602719Z","steps":["trace[1253262942] 'process raft request' (duration: 124.645293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:22.838263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.376683ms","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-04-02T09:05:22.838414Z","caller":"traceutil/trace.go:171","msg":"trace[1566304462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8514; }","duration":"138.578149ms","start":"2026-04-02T09:05:22.699818Z","end":"2026-04-02T09:05:22.838396Z","steps":["trace[1566304462] 'range keys from in-memory index tree' (duration: 138.202348ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:25.132046Z","caller":"traceutil/trace.go:171","msg":"trace[1795492211] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8831; }","duration":"162.046774ms","start":"2026-04-02T09:05:24.96997Z","end":"2026-04-02T09:05:25.132017Z","steps":["trace[1795492211] 'read index received' (duration: 106.547341ms)","trace[1795492211] 'applied index is now lower than readState.Index' (duration: 55.498143ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:05:25.132056Z","caller":"traceutil/trace.go:171","msg":"trace[933281553] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"163.210418ms","start":"2026-04-02T09:05:24.968807Z","end":"2026-04-02T09:05:25.132017Z","steps":["trace[933281553] 'process raft request' (duration: 107.736545ms)","trace[933281553] 'compare' (duration: 55.349369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:05:25.132327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.777057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-02T09:05:25.132321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.334723ms","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-04-02T09:05:25.132371Z","caller":"traceutil/trace.go:171","msg":"trace[409165665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8527; }","duration":"155.919831ms","start":"2026-04-02T09:05:24.97644Z","end":"2026-04-02T09:05:25.13236Z","steps":["trace[409165665] 'agreement among raft nodes before linearized reading' (duration: 155.743736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:25.132401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.908455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T09:05:25.132466Z","caller":"traceutil/trace.go:171","msg":"trace[1777299008] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8527; }","duration":"147.011508ms","start":"2026-04-02T09:05:24.985439Z","end":"2026-04-02T09:05:25.13245Z","steps":["trace[1777299008] 'agreement among raft nodes before linearized reading' (duration: 146.800662ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:25.132393Z","caller":"traceutil/trace.go:171","msg":"trace[1110290138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8527; }","duration":"162.460946ms","start":"2026-04-02T09:05:24.969914Z","end":"2026-04-02T09:05:25.132375Z","steps":["trace[1110290138] 'agreement among raft nodes before linearized reading' (duration: 162.194759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:25.132321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.183841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2dj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T09:05:25.132646Z","caller":"traceutil/trace.go:171","msg":"trace[505435208] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2dj4; range_end:; response_count:1; response_revision:8527; }","duration":"142.475069ms","start":"2026-04-02T09:05:24.990079Z","end":"2026-04-02T09:05:25.132554Z","steps":["trace[505435208] 'agreement among raft nodes before linearized reading' (duration: 142.123899ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:35.283572Z","caller":"traceutil/trace.go:171","msg":"trace[1855738829] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"164.36929ms","start":"2026-04-02T09:05:35.119176Z","end":"2026-04-02T09:05:35.283545Z","steps":["trace[1855738829] 'process raft request' (duration: 125.431696ms)","trace[1855738829] 'compare' (duration: 38.751718ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:05:35.846205Z","caller":"traceutil/trace.go:171","msg":"trace[1741352574] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"187.737372ms","start":"2026-04-02T09:05:35.658424Z","end":"2026-04-02T09:05:35.846162Z","steps":["trace[1741352574] 'process raft request' (duration: 187.491755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:36.811787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.904352ms","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-04-02T09:05:36.81191Z","caller":"traceutil/trace.go:171","msg":"trace[99422898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8635; }","duration":"124.045806ms","start":"2026-04-02T09:05:36.68784Z","end":"2026-04-02T09:05:36.811885Z","steps":["trace[99422898] 'range keys from in-memory index tree' (duration: 123.762568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:36.811648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.514162ms","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-04-02T09:05:36.812396Z","caller":"traceutil/trace.go:171","msg":"trace[1039763132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8635; }","duration":"142.312604ms","start":"2026-04-02T09:05:36.670052Z","end":"2026-04-02T09:05:36.812365Z","steps":["trace[1039763132] 'range keys from in-memory index tree' (duration: 141.296446ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:06:03.091311Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002419.wal"} {"level":"info","ts":"2026-04-02T09:07:31.441879Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T09:07:31.447608Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T09:07:31.44773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T09:08:34.336651Z","caller":"traceutil/trace.go:171","msg":"trace[1185353380] linearizableReadLoop","detail":"{readStateIndex:10448; appliedIndex:10447; }","duration":"459.466715ms","start":"2026-04-02T09:08:33.877139Z","end":"2026-04-02T09:08:34.336605Z","steps":["trace[1185353380] 'read index received' (duration: 456.692746ms)","trace[1185353380] 'applied index is now lower than readState.Index' (duration: 2.772259ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:08:34.336754Z","caller":"traceutil/trace.go:171","msg":"trace[385579590] transaction","detail":"{read_only:false; response_revision:10096; number_of_response:1; }","duration":"583.982073ms","start":"2026-04-02T09:08:33.752702Z","end":"2026-04-02T09:08:34.336684Z","steps":["trace[385579590] 'process raft request' (duration: 581.171934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.336936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.75266Z","time spent":"584.193359ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:08:34.33699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.843745ms","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-04-02T09:08:34.337109Z","caller":"traceutil/trace.go:171","msg":"trace[587135444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10096; }","duration":"459.995979ms","start":"2026-04-02T09:08:33.877091Z","end":"2026-04-02T09:08:34.337086Z","steps":["trace[587135444] 'agreement among raft nodes before linearized reading' (duration: 459.727242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.337596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.877075Z","time spent":"460.507344ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T09:08:34.337926Z","caller":"traceutil/trace.go:171","msg":"trace[1850600978] transaction","detail":"{read_only:false; response_revision:10098; number_of_response:1; }","duration":"192.49138ms","start":"2026-04-02T09:08:34.145411Z","end":"2026-04-02T09:08:34.337902Z","steps":["trace[1850600978] 'process raft request' (duration: 192.334276ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:08:34.337936Z","caller":"traceutil/trace.go:171","msg":"trace[1349083755] transaction","detail":"{read_only:false; response_revision:10097; number_of_response:1; }","duration":"389.889959ms","start":"2026-04-02T09:08:33.94799Z","end":"2026-04-02T09:08:34.33788Z","steps":["trace[1349083755] 'process raft request' (duration: 389.569409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.338128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.947963Z","time spent":"390.078095ms","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2451,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T09:08:34.338158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.086726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T09:08:34.338099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.093234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-02T09:08:34.338226Z","caller":"traceutil/trace.go:171","msg":"trace[548017754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10098; }","duration":"176.19912ms","start":"2026-04-02T09:08:34.162009Z","end":"2026-04-02T09:08:34.338208Z","steps":["trace[548017754] 'agreement among raft nodes before linearized reading' (duration: 176.001594ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:08:34.33823Z","caller":"traceutil/trace.go:171","msg":"trace[2026502622] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:10098; }","duration":"327.266369ms","start":"2026-04-02T09:08:34.010947Z","end":"2026-04-02T09:08:34.338214Z","steps":["trace[2026502622] 'agreement among raft nodes before linearized reading' (duration: 326.96355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.338287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:34.01093Z","time spent":"327.344401ms","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-02T09:08:34.33829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.958406ms","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-04-02T09:08:34.338385Z","caller":"traceutil/trace.go:171","msg":"trace[1142332313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10098; }","duration":"186.07461ms","start":"2026-04-02T09:08:34.152288Z","end":"2026-04-02T09:08:34.338363Z","steps":["trace[1142332313] 'agreement among raft nodes before linearized reading' (duration: 185.880204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.338481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.281516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T09:08:34.338515Z","caller":"traceutil/trace.go:171","msg":"trace[1088744477] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:10098; }","duration":"237.332028ms","start":"2026-04-02T09:08:34.101165Z","end":"2026-04-02T09:08:34.338498Z","steps":["trace[1088744477] 'agreement among raft nodes before linearized reading' (duration: 237.268456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.338973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.5086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-02T09:08:34.339014Z","caller":"traceutil/trace.go:171","msg":"trace[615204605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10098; }","duration":"100.578773ms","start":"2026-04-02T09:08:34.238423Z","end":"2026-04-02T09:08:34.339002Z","steps":["trace[615204605] 'agreement among raft nodes before linearized reading' (duration: 99.604615ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:41.091763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8239} {"level":"info","ts":"2026-04-02T09:09:41.165604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8239,"took":"72.095389ms","hash":2266089021,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":25534464,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-02T09:09:41.165676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266089021,"revision":8239,"compact-revision":6080} {"level":"warn","ts":"2026-04-02T09:09:49.209891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948823048,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T09:09:49.614123Z","caller":"traceutil/trace.go:171","msg":"trace[919199788] linearizableReadLoop","detail":"{readStateIndex:11047; appliedIndex:11046; }","duration":"904.652811ms","start":"2026-04-02T09:09:48.70944Z","end":"2026-04-02T09:09:49.614093Z","steps":["trace[919199788] 'read index received' (duration: 904.355453ms)","trace[919199788] 'applied index is now lower than readState.Index' (duration: 296.308µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:09:49.614463Z","caller":"traceutil/trace.go:171","msg":"trace[1316156554] transaction","detail":"{read_only:false; response_revision:10675; number_of_response:1; }","duration":"958.405971ms","start":"2026-04-02T09:09:48.656044Z","end":"2026-04-02T09:09:49.61445Z","steps":["trace[1316156554] 'process raft request' (duration: 957.701791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:49.61459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.656024Z","time spent":"958.480343ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:49.614792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.32825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"info","ts":"2026-04-02T09:09:49.614916Z","caller":"traceutil/trace.go:171","msg":"trace[742559078] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:10675; }","duration":"905.495395ms","start":"2026-04-02T09:09:48.709391Z","end":"2026-04-02T09:09:49.614886Z","steps":["trace[742559078] 'agreement among raft nodes before linearized reading' (duration: 905.123114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:49.614981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.70937Z","time spent":"905.596788ms","remote":"127.0.0.1:60304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5149,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-02T09:09:50.114791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948823053,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:09:50.615578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409455948823053,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:09:50.681947Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068121473s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T09:09:50.683218Z","caller":"traceutil/trace.go:171","msg":"trace[619687570] transaction","detail":"{read_only:false; response_revision:10676; number_of_response:1; }","duration":"1.445382841s","start":"2026-04-02T09:09:49.237805Z","end":"2026-04-02T09:09:50.683188Z","steps":["trace[619687570] 'process raft request' (duration: 1.44428295s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.68338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.237785Z","time spent":"1.445510455s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:09:50.683926Z","caller":"traceutil/trace.go:171","msg":"trace[1023927868] transaction","detail":"{read_only:false; response_revision:10677; number_of_response:1; }","duration":"1.431797158s","start":"2026-04-02T09:09:49.252114Z","end":"2026-04-02T09:09:50.683911Z","steps":["trace[1023927868] 'process raft request' (duration: 1.431028955s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.683993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.252077Z","time spent":"1.43188463s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:09:50.687531Z","caller":"traceutil/trace.go:171","msg":"trace[1871332107] linearizableReadLoop","detail":"{readStateIndex:11051; appliedIndex:11047; }","duration":"1.0733138s","start":"2026-04-02T09:09:49.614194Z","end":"2026-04-02T09:09:50.687508Z","steps":["trace[1871332107] 'read index received' (duration: 1.068085972s)","trace[1871332107] 'applied index is now lower than readState.Index' (duration: 5.226758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:09:50.687819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717368472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-02T09:09:50.687855Z","caller":"traceutil/trace.go:171","msg":"trace[2060880740] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:10678; }","duration":"1.717400083s","start":"2026-04-02T09:09:48.970441Z","end":"2026-04-02T09:09:50.687842Z","steps":["trace[2060880740] 'agreement among raft nodes before linearized reading' (duration: 1.717338551s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.68783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909190687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-02T09:09:50.687882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.970421Z","time spent":"1.717455995s","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-02T09:09:50.687915Z","caller":"traceutil/trace.go:171","msg":"trace[1657264759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10678; }","duration":"1.90932744s","start":"2026-04-02T09:09:48.778573Z","end":"2026-04-02T09:09:50.6879Z","steps":["trace[1657264759] 'agreement among raft nodes before linearized reading' (duration: 1.909098554s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.778553Z","time spent":"1.909387513s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T09:09:50.688016Z","caller":"traceutil/trace.go:171","msg":"trace[1955502018] transaction","detail":"{read_only:false; response_revision:10678; number_of_response:1; }","duration":"713.381493ms","start":"2026-04-02T09:09:49.974611Z","end":"2026-04-02T09:09:50.687993Z","steps":["trace[1955502018] 'process raft request' (duration: 712.768136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854383337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T09:09:50.688133Z","caller":"traceutil/trace.go:171","msg":"trace[204994388] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10678; }","duration":"1.854820619s","start":"2026-04-02T09:09:48.833306Z","end":"2026-04-02T09:09:50.688126Z","steps":["trace[204994388] 'agreement among raft nodes before linearized reading' (duration: 1.854313745s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.68813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.974593Z","time spent":"713.469565ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:50.688194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423963196s","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-04-02T09:09:50.688207Z","caller":"traceutil/trace.go:171","msg":"trace[1825939647] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10678; }","duration":"1.424008427s","start":"2026-04-02T09:09:49.264195Z","end":"2026-04-02T09:09:50.688204Z","steps":["trace[1825939647] 'agreement among raft nodes before linearized reading' (duration: 1.423964156s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.264181Z","time spent":"1.424035337s","remote":"127.0.0.1:60250","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":"warn","ts":"2026-04-02T09:09:50.687792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622683785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2dj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T09:09:50.688326Z","caller":"traceutil/trace.go:171","msg":"trace[1913203270] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2dj4; range_end:; response_count:1; response_revision:10678; }","duration":"1.623277801s","start":"2026-04-02T09:09:49.065034Z","end":"2026-04-02T09:09:50.688312Z","steps":["trace[1913203270] 'agreement among raft nodes before linearized reading' (duration: 1.622556921s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.065012Z","time spent":"1.623343393s","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-z2dj4\" "} {"level":"warn","ts":"2026-04-02T09:09:50.688379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.907295753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-02T09:09:50.688035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.845903867s","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-04-02T09:09:50.688426Z","caller":"traceutil/trace.go:171","msg":"trace[822828886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10678; }","duration":"1.907325303s","start":"2026-04-02T09:09:48.781076Z","end":"2026-04-02T09:09:50.688402Z","steps":["trace[822828886] 'agreement among raft nodes before linearized reading' (duration: 1.907234171s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.833285Z","time spent":"1.85485981s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T09:09:50.68847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.781043Z","time spent":"1.907420016s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T09:09:50.688438Z","caller":"traceutil/trace.go:171","msg":"trace[928086624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10678; }","duration":"1.846286628s","start":"2026-04-02T09:09:48.842124Z","end":"2026-04-02T09:09:50.688411Z","steps":["trace[928086624] 'agreement among raft nodes before linearized reading' (duration: 1.845864216s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906130289s","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-04-02T09:09:50.688529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.842063Z","time spent":"1.846458003s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T09:09:50.688542Z","caller":"traceutil/trace.go:171","msg":"trace[1701266273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10678; }","duration":"1.906400357s","start":"2026-04-02T09:09:48.782134Z","end":"2026-04-02T09:09:50.688535Z","steps":["trace[1701266273] 'agreement among raft nodes before linearized reading' (duration: 1.906078178s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.782125Z","time spent":"1.906433248s","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T09:09:50.688779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.999167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:09:50.688808Z","caller":"traceutil/trace.go:171","msg":"trace[1736331253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10678; }","duration":"298.053768ms","start":"2026-04-02T09:09:50.390747Z","end":"2026-04-02T09:09:50.688801Z","steps":["trace[1736331253] 'agreement among raft nodes before linearized reading' (duration: 298.005986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.937142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:09:50.688881Z","caller":"traceutil/trace.go:171","msg":"trace[1134506427] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:10678; }","duration":"491.025145ms","start":"2026-04-02T09:09:50.197849Z","end":"2026-04-02T09:09:50.688874Z","steps":["trace[1134506427] 'agreement among raft nodes before linearized reading' (duration: 490.941272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:50.197826Z","time spent":"491.070046ms","remote":"127.0.0.1:60498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:09:50.689223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.874228ms","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-04-02T09:09:50.689405Z","caller":"traceutil/trace.go:171","msg":"trace[2071635883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10678; }","duration":"562.115595ms","start":"2026-04-02T09:09:50.127271Z","end":"2026-04-02T09:09:50.689386Z","steps":["trace[2071635883] 'agreement among raft nodes before linearized reading' (duration: 561.431356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.689541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:50.127248Z","time spent":"562.27924ms","remote":"127.0.0.1:60342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T09:14:41.097192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10638} {"level":"info","ts":"2026-04-02T09:14:41.172711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10638,"took":"73.882298ms","hash":1991368573,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":20754432,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-02T09:14:41.172761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1991368573,"revision":10638,"compact-revision":8239}