{"level":"warn","ts":"2026-04-20T14:18:34.624292Z","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-20T14:18:34.624445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-20T14:18:34.624807Z","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-20T14:18:34.62483Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-04-20T14:18:34.624914Z","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-20T14:18:34.625953Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-04-20T14:18:34.626164Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-20T14:18:34.630851Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.254665ms"} {"level":"info","ts":"2026-04-20T14:18:34.645491Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-04-20T14:18:34.645596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:18:34.645625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-04-20T14:18:34.645637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:18:34.645651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-04-20T14:18:34.645698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-04-20T14:18:34.656434Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:18:34.659238Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:18:34.660898Z","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-20T14:18:34.663118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:18:34.666856Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:18:34.667081Z","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-20T14:18:34.66723Z","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-20T14:18:34.66728Z","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-20T14:18:34.668252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-04-20T14:18:34.67369Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-04-20T14:18:34.674299Z","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-20T14:18:34.674478Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-04-20T14:18:34.674536Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-04-20T14:18:34.674824Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:18:34.674878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:18:35.54749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:18:35.547537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:18:35.547558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-04-20T14:18:35.54757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:18:35.547576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-04-20T14:18:35.547592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-04-20T14:18:35.547599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-04-20T14:18:35.557995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:35.562191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:18:35.562183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:18:35.562545Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:35.562621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:18:35.562652Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:35.56269Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:18:35.562741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:18:35.56276Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:18:35.565287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-04-20T14:18:35.56695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T14:18:40.735705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.846579ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-20T14:18:40.735836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:40.318554Z","time spent":"417.272972ms","remote":"127.0.0.1:51398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T14:18:40.793915Z","caller":"traceutil/trace.go:171","msg":"trace[1615030560] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:215; }","duration":"467.112583ms","start":"2026-04-20T14:18:40.326774Z","end":"2026-04-20T14:18:40.793886Z","steps":["trace[1615030560] 'read index received' (duration: 141.929156ms)","trace[1615030560] 'applied index is now lower than readState.Index' (duration: 325.182447ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:40.794067Z","caller":"traceutil/trace.go:171","msg":"trace[1782945208] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"467.472307ms","start":"2026-04-20T14:18:40.326583Z","end":"2026-04-20T14:18:40.794055Z","steps":["trace[1782945208] 'process raft request' (duration: 467.105234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:40.794213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.416886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T14:18:40.794321Z","caller":"traceutil/trace.go:171","msg":"trace[4898995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:211; }","duration":"467.560108ms","start":"2026-04-20T14:18:40.326742Z","end":"2026-04-20T14:18:40.794302Z","steps":["trace[4898995] 'agreement among raft nodes before linearized reading' (duration: 467.321396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:40.794374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:40.326727Z","time spent":"467.638318ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:18:40.794656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:40.326568Z","time spent":"467.536337ms","remote":"127.0.0.1:51518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:18:40.799711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.593707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:18:40.799787Z","caller":"traceutil/trace.go:171","msg":"trace[381851075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:213; }","duration":"472.705238ms","start":"2026-04-20T14:18:40.327064Z","end":"2026-04-20T14:18:40.799769Z","steps":["trace[381851075] 'agreement among raft nodes before linearized reading' (duration: 472.540316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:40.799927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:40.327045Z","time spent":"472.870149ms","remote":"127.0.0.1:51320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:18:40.799883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.99525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-20T14:18:40.800115Z","caller":"traceutil/trace.go:171","msg":"trace[2032746864] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:213; }","duration":"398.259482ms","start":"2026-04-20T14:18:40.401774Z","end":"2026-04-20T14:18:40.800034Z","steps":["trace[2032746864] 'agreement among raft nodes before linearized reading' (duration: 397.904459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:40.800214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:40.401755Z","time spent":"398.444943ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T14:19:13.377137Z","caller":"traceutil/trace.go:171","msg":"trace[1482890094] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"130.301476ms","start":"2026-04-20T14:19:13.246818Z","end":"2026-04-20T14:19:13.377119Z","steps":["trace[1482890094] 'process raft request' (duration: 129.807112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:14.653132Z","caller":"traceutil/trace.go:171","msg":"trace[1984817401] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"290.653819ms","start":"2026-04-20T14:19:14.362445Z","end":"2026-04-20T14:19:14.653099Z","steps":["trace[1984817401] 'process raft request' (duration: 290.414698ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:14.892541Z","caller":"traceutil/trace.go:171","msg":"trace[1340118720] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"500.780944ms","start":"2026-04-20T14:19:14.391518Z","end":"2026-04-20T14:19:14.892299Z","steps":["trace[1340118720] 'process raft request' (duration: 488.487667ms)","trace[1340118720] 'compare' (duration: 11.887018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:14.892905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:14.39149Z","time spent":"501.135801ms","remote":"127.0.0.1:51428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:19:30.667763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.615583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s5h7n\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-20T14:19:30.667875Z","caller":"traceutil/trace.go:171","msg":"trace[1748412350] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s5h7n; range_end:; response_count:1; response_revision:787; }","duration":"224.79218ms","start":"2026-04-20T14:19:30.443057Z","end":"2026-04-20T14:19:30.667849Z","steps":["trace[1748412350] 'range keys from in-memory index tree' (duration: 224.451527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:30.668062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.290218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T14:19:30.668151Z","caller":"traceutil/trace.go:171","msg":"trace[1316539477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"450.427953ms","start":"2026-04-20T14:19:30.217704Z","end":"2026-04-20T14:19:30.668132Z","steps":["trace[1316539477] 'range keys from in-memory index tree' (duration: 450.10053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:30.668199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:30.217685Z","time spent":"450.503676ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:19:30.668464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.877467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:30.668731Z","caller":"traceutil/trace.go:171","msg":"trace[1675819206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"206.140547ms","start":"2026-04-20T14:19:30.462576Z","end":"2026-04-20T14:19:30.668716Z","steps":["trace[1675819206] 'range keys from in-memory index tree' (duration: 205.812564ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:25.895074Z","caller":"traceutil/trace.go:171","msg":"trace[886997847] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"139.030545ms","start":"2026-04-20T14:20:25.756011Z","end":"2026-04-20T14:20:25.895041Z","steps":["trace[886997847] 'process raft request' (duration: 138.142455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.423708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.841321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:20:51.423787Z","caller":"traceutil/trace.go:171","msg":"trace[875622379] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1873; }","duration":"148.975444ms","start":"2026-04-20T14:20:51.274796Z","end":"2026-04-20T14:20:51.423772Z","steps":["trace[875622379] 'count revisions from in-memory index tree' (duration: 148.726039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.423847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.894947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-20T14:20:51.423928Z","caller":"traceutil/trace.go:171","msg":"trace[1908256289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"259.02696ms","start":"2026-04-20T14:20:51.164873Z","end":"2026-04-20T14:20:51.4239Z","steps":["trace[1908256289] 'range keys from in-memory index tree' (duration: 258.686343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.424175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.988143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T14:20:51.424495Z","caller":"traceutil/trace.go:171","msg":"trace[2045680764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"326.088194ms","start":"2026-04-20T14:20:51.098143Z","end":"2026-04-20T14:20:51.424231Z","steps":["trace[2045680764] 'range keys from in-memory index tree' (duration: 325.645406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.424564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:51.098119Z","time spent":"326.430861ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:20:51.781399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.990496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:20:51.781882Z","caller":"traceutil/trace.go:171","msg":"trace[43070831] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"331.527894ms","start":"2026-04-20T14:20:51.450325Z","end":"2026-04-20T14:20:51.781853Z","steps":["trace[43070831] 'process raft request' (duration: 151.919927ms)","trace[43070831] 'compare' (duration: 178.615549ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:51.78194Z","caller":"traceutil/trace.go:171","msg":"trace[8394822] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"253.953987ms","start":"2026-04-20T14:20:51.527962Z","end":"2026-04-20T14:20:51.781916Z","steps":["trace[8394822] 'process raft request' (duration: 253.5718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:51.781973Z","caller":"traceutil/trace.go:171","msg":"trace[2098706212] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"120.069731ms","start":"2026-04-20T14:20:51.661882Z","end":"2026-04-20T14:20:51.781952Z","steps":["trace[2098706212] 'process raft request' (duration: 119.769346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.782051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:51.450306Z","time spent":"331.665998ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:04.139434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.649564ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T14:21:04.139663Z","caller":"traceutil/trace.go:171","msg":"trace[1596393324] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"104.316378ms","start":"2026-04-20T14:21:04.035332Z","end":"2026-04-20T14:21:04.139648Z","steps":["trace[1596393324] 'process raft request' (duration: 104.194346ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:04.139759Z","caller":"traceutil/trace.go:171","msg":"trace[270071757] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"181.854667ms","start":"2026-04-20T14:21:03.957835Z","end":"2026-04-20T14:21:04.139689Z","steps":["trace[270071757] 'read index received' (duration: 79.95018ms)","trace[270071757] 'applied index is now lower than readState.Index' (duration: 101.901817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:04.14005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.211213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T14:21:04.140657Z","caller":"traceutil/trace.go:171","msg":"trace[1479061163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1980; }","duration":"182.863145ms","start":"2026-04-20T14:21:03.957751Z","end":"2026-04-20T14:21:04.140614Z","steps":["trace[1479061163] 'agreement among raft nodes before linearized reading' (duration: 182.122492ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:07.539656Z","caller":"traceutil/trace.go:171","msg":"trace[34026795] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"122.305937ms","start":"2026-04-20T14:21:07.417329Z","end":"2026-04-20T14:21:07.539635Z","steps":["trace[34026795] 'process raft request' (duration: 121.89705ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:31.827361Z","caller":"traceutil/trace.go:171","msg":"trace[198091975] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"133.637908ms","start":"2026-04-20T14:21:31.693696Z","end":"2026-04-20T14:21:31.827334Z","steps":["trace[198091975] 'process raft request' (duration: 133.511216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:31.997289Z","caller":"traceutil/trace.go:171","msg":"trace[931529645] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"303.454298ms","start":"2026-04-20T14:21:31.693805Z","end":"2026-04-20T14:21:31.997259Z","steps":["trace[931529645] 'process raft request' (duration: 260.434915ms)","trace[931529645] 'compare' (duration: 42.80332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:31.997497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:31.693784Z","time spent":"303.60864ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:32.094669Z","caller":"traceutil/trace.go:171","msg":"trace[1678920545] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"115.046838ms","start":"2026-04-20T14:21:31.979577Z","end":"2026-04-20T14:21:32.094624Z","steps":["trace[1678920545] 'process raft request' (duration: 114.821104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:03.055594Z","caller":"traceutil/trace.go:171","msg":"trace[548640315] linearizableReadLoop","detail":"{readStateIndex:3171; appliedIndex:3170; }","duration":"305.026558ms","start":"2026-04-20T14:24:02.750535Z","end":"2026-04-20T14:24:03.055562Z","steps":["trace[548640315] 'read index received' (duration: 304.629614ms)","trace[548640315] 'applied index is now lower than readState.Index' (duration: 395.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:03.056024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.461843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:03.056127Z","caller":"traceutil/trace.go:171","msg":"trace[1357200967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3056; }","duration":"305.613419ms","start":"2026-04-20T14:24:02.750493Z","end":"2026-04-20T14:24:03.056107Z","steps":["trace[1357200967] 'agreement among raft nodes before linearized reading' (duration: 305.439953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:03.056194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:02.750475Z","time spent":"305.711432ms","remote":"127.0.0.1:51320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:24:03.056753Z","caller":"traceutil/trace.go:171","msg":"trace[1953884415] transaction","detail":"{read_only:false; response_revision:3056; number_of_response:1; }","duration":"330.997827ms","start":"2026-04-20T14:24:02.725644Z","end":"2026-04-20T14:24:03.056642Z","steps":["trace[1953884415] 'process raft request' (duration: 329.707012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:03.057836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:02.725623Z","time spent":"331.469384ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:24:03.058121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.294421ms","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-20T14:24:03.058167Z","caller":"traceutil/trace.go:171","msg":"trace[1419496957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3056; }","duration":"133.384115ms","start":"2026-04-20T14:24:02.92477Z","end":"2026-04-20T14:24:03.058154Z","steps":["trace[1419496957] 'agreement among raft nodes before linearized reading' (duration: 133.25611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:03.058126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.660398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-20T14:24:03.058262Z","caller":"traceutil/trace.go:171","msg":"trace[1393654546] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3056; }","duration":"231.839083ms","start":"2026-04-20T14:24:02.82641Z","end":"2026-04-20T14:24:03.058249Z","steps":["trace[1393654546] 'agreement among raft nodes before linearized reading' (duration: 231.579414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:14.807702Z","caller":"traceutil/trace.go:171","msg":"trace[26953484] linearizableReadLoop","detail":"{readStateIndex:3989; appliedIndex:3988; }","duration":"379.027063ms","start":"2026-04-20T14:26:14.428576Z","end":"2026-04-20T14:26:14.807603Z","steps":["trace[26953484] 'read index received' (duration: 374.69147ms)","trace[26953484] 'applied index is now lower than readState.Index' (duration: 4.334353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:14.807916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.312359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:26:14.807953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.139026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:14.808009Z","caller":"traceutil/trace.go:171","msg":"trace[118569474] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:3843; }","duration":"379.478123ms","start":"2026-04-20T14:26:14.428513Z","end":"2026-04-20T14:26:14.807991Z","steps":["trace[118569474] 'agreement among raft nodes before linearized reading' (duration: 379.246448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:14.808047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.428489Z","time spent":"379.550875ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":21,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:26:14.808059Z","caller":"traceutil/trace.go:171","msg":"trace[852903513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3843; }","duration":"345.246148ms","start":"2026-04-20T14:26:14.46278Z","end":"2026-04-20T14:26:14.808026Z","steps":["trace[852903513] 'agreement among raft nodes before linearized reading' (duration: 345.103835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:14.808139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.776212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:26:14.808267Z","caller":"traceutil/trace.go:171","msg":"trace[454505849] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3843; }","duration":"271.959015ms","start":"2026-04-20T14:26:14.536261Z","end":"2026-04-20T14:26:14.80822Z","steps":["trace[454505849] 'agreement among raft nodes before linearized reading' (duration: 271.742561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:14.808424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.462728Z","time spent":"345.683848ms","remote":"127.0.0.1:51320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:27:12.477985Z","caller":"traceutil/trace.go:171","msg":"trace[669993502] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"114.918322ms","start":"2026-04-20T14:27:12.363039Z","end":"2026-04-20T14:27:12.477958Z","steps":["trace[669993502] 'process raft request' (duration: 114.381412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:18.190734Z","caller":"traceutil/trace.go:171","msg":"trace[1586091109] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"103.353513ms","start":"2026-04-20T14:27:18.087349Z","end":"2026-04-20T14:27:18.190703Z","steps":["trace[1586091109] 'process raft request' (duration: 103.291922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:18.190803Z","caller":"traceutil/trace.go:171","msg":"trace[1343443781] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"142.688401ms","start":"2026-04-20T14:27:18.048065Z","end":"2026-04-20T14:27:18.190753Z","steps":["trace[1343443781] 'process raft request' (duration: 142.499768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:18.19078Z","caller":"traceutil/trace.go:171","msg":"trace[295752357] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"164.363832ms","start":"2026-04-20T14:27:18.026364Z","end":"2026-04-20T14:27:18.190728Z","steps":["trace[295752357] 'process raft request' (duration: 147.55358ms)","trace[295752357] 'compare' (duration: 16.444795ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:18.48014Z","caller":"traceutil/trace.go:171","msg":"trace[1620677125] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"278.491854ms","start":"2026-04-20T14:27:18.201621Z","end":"2026-04-20T14:27:18.480113Z","steps":["trace[1620677125] 'process raft request' (duration: 277.974734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.309194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.618312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:27:24.309616Z","caller":"traceutil/trace.go:171","msg":"trace[2001103557] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"156.955732ms","start":"2026-04-20T14:27:24.15263Z","end":"2026-04-20T14:27:24.309585Z","steps":["trace[2001103557] 'process raft request' (duration: 156.809669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.309661Z","caller":"traceutil/trace.go:171","msg":"trace[1478112606] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5043; }","duration":"183.321494ms","start":"2026-04-20T14:27:24.126295Z","end":"2026-04-20T14:27:24.309617Z","steps":["trace[1478112606] 'read index received' (duration: 18.195623ms)","trace[1478112606] 'applied index is now lower than readState.Index' (duration: 165.122951ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:24.309777Z","caller":"traceutil/trace.go:171","msg":"trace[2089739602] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"186.995941ms","start":"2026-04-20T14:27:24.122729Z","end":"2026-04-20T14:27:24.309725Z","steps":["trace[2089739602] 'process raft request' (duration: 21.679526ms)","trace[2089739602] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/statefulsets/openstack/rabbitmq-keystone-server; req_size:9619; } (duration: 164.230795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:24.310021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.722441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3223"} {"level":"info","ts":"2026-04-20T14:27:24.310108Z","caller":"traceutil/trace.go:171","msg":"trace[431949189] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4862; }","duration":"183.832944ms","start":"2026-04-20T14:27:24.126253Z","end":"2026-04-20T14:27:24.310085Z","steps":["trace[431949189] 'agreement among raft nodes before linearized reading' (duration: 183.536508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.322184Z","caller":"traceutil/trace.go:171","msg":"trace[1099310639] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"111.649133ms","start":"2026-04-20T14:27:24.210509Z","end":"2026-04-20T14:27:24.322158Z","steps":["trace[1099310639] 'process raft request' (duration: 110.96552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.461291Z","caller":"traceutil/trace.go:171","msg":"trace[909804574] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"141.091351ms","start":"2026-04-20T14:27:24.320188Z","end":"2026-04-20T14:27:24.461279Z","steps":["trace[909804574] 'process raft request' (duration: 93.421779ms)","trace[909804574] 'compare' (duration: 46.607413ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:24.461224Z","caller":"traceutil/trace.go:171","msg":"trace[1754939053] linearizableReadLoop","detail":"{readStateIndex:5047; appliedIndex:5046; }","duration":"136.022669ms","start":"2026-04-20T14:27:24.325178Z","end":"2026-04-20T14:27:24.461201Z","steps":["trace[1754939053] 'read index received' (duration: 89.10303ms)","trace[1754939053] 'applied index is now lower than readState.Index' (duration: 46.918759ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:24.461797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.598459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-20T14:27:24.461842Z","caller":"traceutil/trace.go:171","msg":"trace[515411743] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4864; }","duration":"136.66115ms","start":"2026-04-20T14:27:24.325169Z","end":"2026-04-20T14:27:24.46183Z","steps":["trace[515411743] 'agreement among raft nodes before linearized reading' (duration: 136.467807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.476026Z","caller":"traceutil/trace.go:171","msg":"trace[1087271909] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"148.06238ms","start":"2026-04-20T14:27:24.327935Z","end":"2026-04-20T14:27:24.475998Z","steps":["trace[1087271909] 'process raft request' (duration: 147.448498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.476495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.144226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02ca421c-c7ba-4e60-9900-ade75246d69b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T14:27:24.476594Z","caller":"traceutil/trace.go:171","msg":"trace[792239715] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02ca421c-c7ba-4e60-9900-ade75246d69b; range_end:; response_count:1; response_revision:4866; }","duration":"151.264568ms","start":"2026-04-20T14:27:24.3253Z","end":"2026-04-20T14:27:24.476564Z","steps":["trace[792239715] 'agreement among raft nodes before linearized reading' (duration: 150.890841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.476761Z","caller":"traceutil/trace.go:171","msg":"trace[1631826200] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"134.794476ms","start":"2026-04-20T14:27:24.341949Z","end":"2026-04-20T14:27:24.476743Z","steps":["trace[1631826200] 'process raft request' (duration: 133.640115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.477178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.368351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-20T14:27:24.477214Z","caller":"traceutil/trace.go:171","msg":"trace[1524747178] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4866; }","duration":"121.444122ms","start":"2026-04-20T14:27:24.355761Z","end":"2026-04-20T14:27:24.477205Z","steps":["trace[1524747178] 'agreement among raft nodes before linearized reading' (duration: 121.34185ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.89802Z","caller":"traceutil/trace.go:171","msg":"trace[620000592] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"274.675466ms","start":"2026-04-20T14:27:24.623317Z","end":"2026-04-20T14:27:24.897993Z","steps":["trace[620000592] 'process raft request' (duration: 274.522243ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.898159Z","caller":"traceutil/trace.go:171","msg":"trace[947346892] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5056; }","duration":"271.264173ms","start":"2026-04-20T14:27:24.626864Z","end":"2026-04-20T14:27:24.898128Z","steps":["trace[947346892] 'read index received' (duration: 271.246803ms)","trace[947346892] 'applied index is now lower than readState.Index' (duration: 13.56µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:24.898486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.597709ms","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-20T14:27:24.89853Z","caller":"traceutil/trace.go:171","msg":"trace[1495522006] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4873; }","duration":"271.68466ms","start":"2026-04-20T14:27:24.626834Z","end":"2026-04-20T14:27:24.898518Z","steps":["trace[1495522006] 'agreement among raft nodes before linearized reading' (duration: 271.430736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.909287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.842966ms","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-20T14:27:24.90938Z","caller":"traceutil/trace.go:171","msg":"trace[1073495300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4874; }","duration":"194.005969ms","start":"2026-04-20T14:27:24.715335Z","end":"2026-04-20T14:27:24.909341Z","steps":["trace[1073495300] 'agreement among raft nodes before linearized reading' (duration: 193.823696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.909381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.610557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-04-20T14:27:24.909288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.969959ms","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-20T14:27:24.909551Z","caller":"traceutil/trace.go:171","msg":"trace[1286862917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4874; }","duration":"182.291475ms","start":"2026-04-20T14:27:24.727252Z","end":"2026-04-20T14:27:24.909543Z","steps":["trace[1286862917] 'agreement among raft nodes before linearized reading' (duration: 181.894037ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:24.909597Z","caller":"traceutil/trace.go:171","msg":"trace[1289160300] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4874; }","duration":"272.869362ms","start":"2026-04-20T14:27:24.636658Z","end":"2026-04-20T14:27:24.909527Z","steps":["trace[1289160300] 'agreement among raft nodes before linearized reading' (duration: 272.463755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:24.909792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.322764ms","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-20T14:27:24.90982Z","caller":"traceutil/trace.go:171","msg":"trace[67580218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4874; }","duration":"259.393166ms","start":"2026-04-20T14:27:24.65042Z","end":"2026-04-20T14:27:24.909813Z","steps":["trace[67580218] 'agreement among raft nodes before linearized reading' (duration: 259.317514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:36.772185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.888729ms","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-20T14:27:36.772276Z","caller":"traceutil/trace.go:171","msg":"trace[1058379797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5046; }","duration":"143.044062ms","start":"2026-04-20T14:27:36.629211Z","end":"2026-04-20T14:27:36.772255Z","steps":["trace[1058379797] 'range keys from in-memory index tree' (duration: 142.602274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:36.772243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.763739ms","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-20T14:27:36.772341Z","caller":"traceutil/trace.go:171","msg":"trace[1580388944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5046; }","duration":"246.979163ms","start":"2026-04-20T14:27:36.525338Z","end":"2026-04-20T14:27:36.772317Z","steps":["trace[1580388944] 'range keys from in-memory index tree' (duration: 246.440734ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:37.202767Z","caller":"traceutil/trace.go:171","msg":"trace[1820998738] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"108.909858ms","start":"2026-04-20T14:27:37.093824Z","end":"2026-04-20T14:27:37.202733Z","steps":["trace[1820998738] 'process raft request' (duration: 77.602298ms)","trace[1820998738] 'compare' (duration: 31.074265ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:37.203399Z","caller":"traceutil/trace.go:171","msg":"trace[1849473562] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"109.000069ms","start":"2026-04-20T14:27:37.094359Z","end":"2026-04-20T14:27:37.203359Z","steps":["trace[1849473562] 'process raft request' (duration: 108.323827ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:37.20377Z","caller":"traceutil/trace.go:171","msg":"trace[1198334045] linearizableReadLoop","detail":"{readStateIndex:5247; appliedIndex:5242; }","duration":"109.482237ms","start":"2026-04-20T14:27:37.094236Z","end":"2026-04-20T14:27:37.203719Z","steps":["trace[1198334045] 'read index received' (duration: 18.074533ms)","trace[1198334045] 'applied index is now lower than readState.Index' (duration: 91.406224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:37.20412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.173468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T14:27:37.204191Z","caller":"traceutil/trace.go:171","msg":"trace[37528035] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5061; }","duration":"111.261559ms","start":"2026-04-20T14:27:37.092907Z","end":"2026-04-20T14:27:37.204169Z","steps":["trace[37528035] 'agreement among raft nodes before linearized reading' (duration: 111.022905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.856752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2917} {"level":"info","ts":"2026-04-20T14:28:35.955431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2917,"took":"96.921575ms","hash":307828389,"current-db-size-bytes":53690368,"current-db-size":"54 MB","current-db-size-in-use-bytes":28626944,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T14:28:35.955513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":307828389,"revision":2917,"compact-revision":-1} {"level":"info","ts":"2026-04-20T14:33:35.863497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5531} {"level":"info","ts":"2026-04-20T14:33:35.959503Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5531,"took":"94.405852ms","hash":945624886,"current-db-size-bytes":53690368,"current-db-size":"54 MB","current-db-size-in-use-bytes":18374656,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T14:33:35.959577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":945624886,"revision":5531,"compact-revision":2917}