{"level":"warn","ts":"2026-05-05T06:48:31.737596Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:48:31.737683Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-05-05T06:48:31.737752Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:48:31.737765Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-05T06:48:31.737784Z","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-05-05T06:48:31.738387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-05T06:48:31.738474Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-05-05T06:48:31.743495Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.808142ms"} {"level":"info","ts":"2026-05-05T06:48:31.751181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-05T06:48:31.751346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:48:31.751393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-05T06:48:31.751409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:48:31.751433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-05T06:48:31.751488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-05T06:48:31.757022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:48:31.75833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:48:31.759813Z","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-05-05T06:48:31.762912Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:48:31.7631Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:48:31.763264Z","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-05-05T06:48:31.76331Z","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-05-05T06:48:31.763319Z","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-05-05T06:48:31.764103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-05T06:48:31.764308Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-05T06:48:31.769803Z","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-05-05T06:48:31.770052Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:48:31.770084Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:48:31.770146Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-05T06:48:31.770172Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-05T06:48:32.552174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:48:32.552274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:48:32.552354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-05T06:48:32.552385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:48:32.552427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-05T06:48:32.552471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-05T06:48:32.55249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-05T06:48:32.553703Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:48:32.553722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:48:32.553774Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:32.554021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:48:32.555219Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:32.555347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:32.555385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:32.556949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:48:32.557639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-05T06:48:32.566806Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:48:32.566901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:49:07.912697Z","caller":"traceutil/trace.go:171","msg":"trace[880272048] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"152.865365ms","start":"2026-05-05T06:49:07.759805Z","end":"2026-05-05T06:49:07.91267Z","steps":["trace[880272048] 'process raft request' (duration: 152.340951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:08.201499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.540644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:49:08.201858Z","caller":"traceutil/trace.go:171","msg":"trace[355025309] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"235.748371ms","start":"2026-05-05T06:49:07.966021Z","end":"2026-05-05T06:49:08.201769Z","steps":["trace[355025309] 'process raft request' (duration: 110.419365ms)","trace[355025309] 'compare' (duration: 124.403361ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:08.20214Z","caller":"traceutil/trace.go:171","msg":"trace[1089258786] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"102.149426ms","start":"2026-05-05T06:49:08.099918Z","end":"2026-05-05T06:49:08.202067Z","steps":["trace[1089258786] 'process raft request' (duration: 101.811487ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:08.556919Z","caller":"traceutil/trace.go:171","msg":"trace[1622478004] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"349.542458ms","start":"2026-05-05T06:49:08.207342Z","end":"2026-05-05T06:49:08.556884Z","steps":["trace[1622478004] 'process raft request' (duration: 349.431345ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:08.557014Z","caller":"traceutil/trace.go:171","msg":"trace[1649003645] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"350.429373ms","start":"2026-05-05T06:49:08.206555Z","end":"2026-05-05T06:49:08.556984Z","steps":["trace[1649003645] 'process raft request' (duration: 260.702243ms)","trace[1649003645] 'compare' (duration: 89.306748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:08.557196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.173723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:08.556888Z","caller":"traceutil/trace.go:171","msg":"trace[263157038] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"342.854273ms","start":"2026-05-05T06:49:08.214006Z","end":"2026-05-05T06:49:08.55686Z","steps":["trace[263157038] 'read index received' (duration: 253.186406ms)","trace[263157038] 'applied index is now lower than readState.Index' (duration: 89.666917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:08.557336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:08.206535Z","time spent":"350.564836ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:08.557258Z","caller":"traceutil/trace.go:171","msg":"trace[977019285] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:619; }","duration":"343.290916ms","start":"2026-05-05T06:49:08.21395Z","end":"2026-05-05T06:49:08.557241Z","steps":["trace[977019285] 'agreement among raft nodes before linearized reading' (duration: 343.152812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:08.557493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:08.213932Z","time spent":"343.538432ms","remote":"127.0.0.1:40354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-05T06:49:08.55725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:08.207334Z","time spent":"349.654271ms","remote":"127.0.0.1:40378","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-05-05T06:49:08.557765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.399942ms","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-05-05T06:49:08.557832Z","caller":"traceutil/trace.go:171","msg":"trace[1317909675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"127.522695ms","start":"2026-05-05T06:49:08.430291Z","end":"2026-05-05T06:49:08.557814Z","steps":["trace[1317909675] 'agreement among raft nodes before linearized reading' (duration: 127.269548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:08.558693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.513548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6kh7h\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T06:49:08.55878Z","caller":"traceutil/trace.go:171","msg":"trace[1940422578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6kh7h; range_end:; response_count:1; response_revision:619; }","duration":"281.621331ms","start":"2026-05-05T06:49:08.277135Z","end":"2026-05-05T06:49:08.558757Z","steps":["trace[1940422578] 'agreement among raft nodes before linearized reading' (duration: 280.386357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:33.266791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.421883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T06:50:33.266889Z","caller":"traceutil/trace.go:171","msg":"trace[1065060328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1810; }","duration":"227.559938ms","start":"2026-05-05T06:50:33.039308Z","end":"2026-05-05T06:50:33.266868Z","steps":["trace[1065060328] 'range keys from in-memory index tree' (duration: 227.236768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:33.267006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.205107ms","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-05-05T06:50:33.267118Z","caller":"traceutil/trace.go:171","msg":"trace[2113833415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1810; }","duration":"296.347641ms","start":"2026-05-05T06:50:32.970745Z","end":"2026-05-05T06:50:33.267093Z","steps":["trace[2113833415] 'range keys from in-memory index tree' (duration: 296.044062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:33.26714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.045171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T06:50:33.267235Z","caller":"traceutil/trace.go:171","msg":"trace[1041710131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1810; }","duration":"235.187135ms","start":"2026-05-05T06:50:33.032027Z","end":"2026-05-05T06:50:33.267214Z","steps":["trace[1041710131] 'range keys from in-memory index tree' (duration: 234.892137ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:33.414196Z","caller":"traceutil/trace.go:171","msg":"trace[1422699102] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"142.664775ms","start":"2026-05-05T06:50:33.271507Z","end":"2026-05-05T06:50:33.414172Z","steps":["trace[1422699102] 'process raft request' (duration: 142.615274ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:33.414226Z","caller":"traceutil/trace.go:171","msg":"trace[440237621] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"142.81183ms","start":"2026-05-05T06:50:33.271383Z","end":"2026-05-05T06:50:33.414195Z","steps":["trace[440237621] 'process raft request' (duration: 107.850208ms)","trace[440237621] 'compare' (duration: 34.736626ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:23.783686Z","caller":"traceutil/trace.go:171","msg":"trace[612463059] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"108.107504ms","start":"2026-05-05T06:51:23.675555Z","end":"2026-05-05T06:51:23.783663Z","steps":["trace[612463059] 'process raft request' (duration: 107.95767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:24.046438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.609542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:51:24.046558Z","caller":"traceutil/trace.go:171","msg":"trace[1882213710] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2220; }","duration":"330.4424ms","start":"2026-05-05T06:51:23.716091Z","end":"2026-05-05T06:51:24.046533Z","steps":["trace[1882213710] 'read index received' (duration: 67.458653ms)","trace[1882213710] 'applied index is now lower than readState.Index' (duration: 262.982487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:24.046716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.624585ms","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-05-05T06:51:24.046759Z","caller":"traceutil/trace.go:171","msg":"trace[467093908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2142; }","duration":"330.703298ms","start":"2026-05-05T06:51:23.716043Z","end":"2026-05-05T06:51:24.046746Z","steps":["trace[467093908] 'agreement among raft nodes before linearized reading' (duration: 330.549274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:24.046793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:23.716023Z","time spent":"330.761849ms","remote":"127.0.0.1:40378","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-05-05T06:51:24.0468Z","caller":"traceutil/trace.go:171","msg":"trace[1786827513] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"371.124633ms","start":"2026-05-05T06:51:23.675609Z","end":"2026-05-05T06:51:24.046734Z","steps":["trace[1786827513] 'process raft request' (duration: 256.138681ms)","trace[1786827513] 'compare' (duration: 114.498698ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:24.047038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:23.675587Z","time spent":"371.320527ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:51:24.243681Z","caller":"traceutil/trace.go:171","msg":"trace[119099580] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"191.687029ms","start":"2026-05-05T06:51:24.05196Z","end":"2026-05-05T06:51:24.243647Z","steps":["trace[119099580] 'process raft request' (duration: 150.040451ms)","trace[119099580] 'compare' (duration: 41.509005ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:12.160153Z","caller":"traceutil/trace.go:171","msg":"trace[452899035] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"157.111144ms","start":"2026-05-05T06:52:12.003014Z","end":"2026-05-05T06:52:12.160126Z","steps":["trace[452899035] 'process raft request' (duration: 156.945449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:52:46.083904Z","caller":"traceutil/trace.go:171","msg":"trace[1360540441] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"151.480294ms","start":"2026-05-05T06:52:45.932399Z","end":"2026-05-05T06:52:46.08388Z","steps":["trace[1360540441] 'process raft request' (duration: 151.328869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:37.801092Z","caller":"traceutil/trace.go:171","msg":"trace[1232083247] linearizableReadLoop","detail":"{readStateIndex:3567; appliedIndex:3566; }","duration":"355.824794ms","start":"2026-05-05T06:54:37.445244Z","end":"2026-05-05T06:54:37.801069Z","steps":["trace[1232083247] 'read index received' (duration: 355.707741ms)","trace[1232083247] 'applied index is now lower than readState.Index' (duration: 115.633µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:37.80116Z","caller":"traceutil/trace.go:171","msg":"trace[820285995] transaction","detail":"{read_only:false; response_revision:3433; number_of_response:1; }","duration":"360.437089ms","start":"2026-05-05T06:54:37.440688Z","end":"2026-05-05T06:54:37.801125Z","steps":["trace[820285995] 'process raft request' (duration: 360.235623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:37.801348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:37.440659Z","time spent":"360.585633ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:54:37.801368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.095352ms","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-05-05T06:54:37.801427Z","caller":"traceutil/trace.go:171","msg":"trace[903600198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3433; }","duration":"356.207055ms","start":"2026-05-05T06:54:37.445204Z","end":"2026-05-05T06:54:37.801411Z","steps":["trace[903600198] 'agreement among raft nodes before linearized reading' (duration: 355.982609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:37.801498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:37.445185Z","time spent":"356.302628ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:54:37.801876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.928434ms","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-05-05T06:54:37.801921Z","caller":"traceutil/trace.go:171","msg":"trace[926796630] transaction","detail":"{read_only:false; response_revision:3434; number_of_response:1; }","duration":"112.930694ms","start":"2026-05-05T06:54:37.688972Z","end":"2026-05-05T06:54:37.801903Z","steps":["trace[926796630] 'process raft request' (duration: 112.626356ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:37.801938Z","caller":"traceutil/trace.go:171","msg":"trace[1457773217] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3434; }","duration":"134.018116ms","start":"2026-05-05T06:54:37.667904Z","end":"2026-05-05T06:54:37.801922Z","steps":["trace[1457773217] 'agreement among raft nodes before linearized reading' (duration: 133.78094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:38.029451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.172492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:54:38.029522Z","caller":"traceutil/trace.go:171","msg":"trace[294321695] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3437; }","duration":"130.292995ms","start":"2026-05-05T06:54:37.899214Z","end":"2026-05-05T06:54:38.029507Z","steps":["trace[294321695] 'count revisions from in-memory index tree' (duration: 130.10237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.332659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367464976623256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:54:46.833414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367464976623256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:54:46.945076Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.121976152s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T06:54:46.946592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92245582s","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-05-05T06:54:46.946666Z","caller":"traceutil/trace.go:171","msg":"trace[2019344639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3602; }","duration":"1.922580354s","start":"2026-05-05T06:54:45.02407Z","end":"2026-05-05T06:54:46.94665Z","steps":["trace[2019344639] 'range keys from in-memory index tree' (duration: 1.922313716s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.946699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.024046Z","time spent":"1.922645545s","remote":"127.0.0.1:40378","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-05-05T06:54:46.946749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292656753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T06:54:46.946786Z","caller":"traceutil/trace.go:171","msg":"trace[1425619825] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:3602; }","duration":"1.292718804s","start":"2026-05-05T06:54:45.654057Z","end":"2026-05-05T06:54:46.946776Z","steps":["trace[1425619825] 'range keys from in-memory index tree' (duration: 1.29257214s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.946806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.654042Z","time spent":"1.292758526s","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-05T06:54:46.94767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681225115s","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-05-05T06:54:46.947701Z","caller":"traceutil/trace.go:171","msg":"trace[1161404801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3602; }","duration":"1.681305997s","start":"2026-05-05T06:54:45.266388Z","end":"2026-05-05T06:54:46.947694Z","steps":["trace[1161404801] 'range keys from in-memory index tree' (duration: 1.681135552s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.947724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.266372Z","time spent":"1.681345599s","remote":"127.0.0.1:40378","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-05-05T06:54:46.947717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778953257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxf7s\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T06:54:46.947804Z","caller":"traceutil/trace.go:171","msg":"trace[1433648842] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxf7s; range_end:; response_count:1; response_revision:3602; }","duration":"1.77908926s","start":"2026-05-05T06:54:45.16869Z","end":"2026-05-05T06:54:46.94778Z","steps":["trace[1433648842] 'range keys from in-memory index tree' (duration: 1.778794473s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.94786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.16867Z","time spent":"1.779177362s","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-jxf7s\" "} {"level":"info","ts":"2026-05-05T06:54:46.948031Z","caller":"traceutil/trace.go:171","msg":"trace[88426781] transaction","detail":"{read_only:false; response_revision:3603; number_of_response:1; }","duration":"1.124993133s","start":"2026-05-05T06:54:45.823014Z","end":"2026-05-05T06:54:46.948007Z","steps":["trace[88426781] 'process raft request' (duration: 1.122316811s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.94818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.822991Z","time spent":"1.125104697s","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:54:46.94823Z","caller":"traceutil/trace.go:171","msg":"trace[738960488] transaction","detail":"{read_only:false; response_revision:3604; number_of_response:1; }","duration":"1.125155758s","start":"2026-05-05T06:54:45.823049Z","end":"2026-05-05T06:54:46.948205Z","steps":["trace[738960488] 'process raft request' (duration: 1.124843689s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.823029Z","time spent":"1.125272251s","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:54:46.948507Z","caller":"traceutil/trace.go:171","msg":"trace[91112452] linearizableReadLoop","detail":"{readStateIndex:3740; appliedIndex:3737; }","duration":"1.116417081s","start":"2026-05-05T06:54:45.83208Z","end":"2026-05-05T06:54:46.948497Z","steps":["trace[91112452] 'read index received' (duration: 1.113245505s)","trace[91112452] 'applied index is now lower than readState.Index' (duration: 3.170076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:54:46.948673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111158748s","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-05-05T06:54:46.948632Z","caller":"traceutil/trace.go:171","msg":"trace[2127810388] transaction","detail":"{read_only:false; response_revision:3605; number_of_response:1; }","duration":"154.637366ms","start":"2026-05-05T06:54:46.793928Z","end":"2026-05-05T06:54:46.948565Z","steps":["trace[2127810388] 'process raft request' (duration: 154.199694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116596845s","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-05-05T06:54:46.948695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.25987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:46.948765Z","caller":"traceutil/trace.go:171","msg":"trace[1417936572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3605; }","duration":"1.116693329s","start":"2026-05-05T06:54:45.832055Z","end":"2026-05-05T06:54:46.948748Z","steps":["trace[1417936572] 'agreement among raft nodes before linearized reading' (duration: 1.116474303s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:46.948777Z","caller":"traceutil/trace.go:171","msg":"trace[1843954956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3605; }","duration":"686.368293ms","start":"2026-05-05T06:54:46.262389Z","end":"2026-05-05T06:54:46.948757Z","steps":["trace[1843954956] 'agreement among raft nodes before linearized reading' (duration: 686.221139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:46.948709Z","caller":"traceutil/trace.go:171","msg":"trace[1672886215] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3605; }","duration":"1.111205179s","start":"2026-05-05T06:54:45.837493Z","end":"2026-05-05T06:54:46.948698Z","steps":["trace[1672886215] 'agreement among raft nodes before linearized reading' (duration: 1.111087876s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.370759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:54:46.948968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.837484Z","time spent":"1.111402464s","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T06:54:46.948817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.262372Z","time spent":"686.435014ms","remote":"127.0.0.1:40088","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-05-05T06:54:46.948712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.588062ms","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-05-05T06:54:46.949745Z","caller":"traceutil/trace.go:171","msg":"trace[399916479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3605; }","duration":"236.662411ms","start":"2026-05-05T06:54:46.713068Z","end":"2026-05-05T06:54:46.94973Z","steps":["trace[399916479] 'agreement among raft nodes before linearized reading' (duration: 235.537681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.832047Z","time spent":"1.11675509s","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T06:54:46.949059Z","caller":"traceutil/trace.go:171","msg":"trace[1043350693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3605; }","duration":"900.626975ms","start":"2026-05-05T06:54:46.048341Z","end":"2026-05-05T06:54:46.948968Z","steps":["trace[1043350693] 'agreement among raft nodes before linearized reading' (duration: 900.289206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.949902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.048324Z","time spent":"901.567271ms","remote":"127.0.0.1:40084","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-05-05T06:54:46.949191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.719737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T06:54:46.95003Z","caller":"traceutil/trace.go:171","msg":"trace[499691183] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3605; }","duration":"117.583281ms","start":"2026-05-05T06:54:46.832425Z","end":"2026-05-05T06:54:46.950008Z","steps":["trace[499691183] 'agreement among raft nodes before linearized reading' (duration: 116.262175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:36.910138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.719778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:56:36.910258Z","caller":"traceutil/trace.go:171","msg":"trace[50429007] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"243.425554ms","start":"2026-05-05T06:56:36.666815Z","end":"2026-05-05T06:56:36.910241Z","steps":["trace[50429007] 'read index received' (duration: 105.53058ms)","trace[50429007] 'applied index is now lower than readState.Index' (duration: 137.893844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:56:36.910357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.540636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:56:36.910394Z","caller":"traceutil/trace.go:171","msg":"trace[1014140740] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4910; }","duration":"243.600878ms","start":"2026-05-05T06:56:36.666781Z","end":"2026-05-05T06:56:36.910382Z","steps":["trace[1014140740] 'agreement among raft nodes before linearized reading' (duration: 243.542177ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:36.910373Z","caller":"traceutil/trace.go:171","msg":"trace[785301793] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"260.594983ms","start":"2026-05-05T06:56:36.649724Z","end":"2026-05-05T06:56:36.910319Z","steps":["trace[785301793] 'process raft request' (duration: 122.614227ms)","trace[785301793] 'compare' (duration: 137.596965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:56:36.910481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.941558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:56:36.910529Z","caller":"traceutil/trace.go:171","msg":"trace[1298457361] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4910; }","duration":"148.03735ms","start":"2026-05-05T06:56:36.762479Z","end":"2026-05-05T06:56:36.910516Z","steps":["trace[1298457361] 'agreement among raft nodes before linearized reading' (duration: 147.960848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:48.283901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.261626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:56:48.284058Z","caller":"traceutil/trace.go:171","msg":"trace[1230376237] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5155; }","duration":"121.481942ms","start":"2026-05-05T06:56:48.16255Z","end":"2026-05-05T06:56:48.284032Z","steps":["trace[1230376237] 'range keys from in-memory index tree' (duration: 121.173783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.722224Z","caller":"traceutil/trace.go:171","msg":"trace[1911347660] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"200.073175ms","start":"2026-05-05T06:57:11.522127Z","end":"2026-05-05T06:57:11.7222Z","steps":["trace[1911347660] 'process raft request' (duration: 199.919741ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.959534Z","caller":"traceutil/trace.go:171","msg":"trace[2087862836] linearizableReadLoop","detail":"{readStateIndex:5681; appliedIndex:5678; }","duration":"397.232879ms","start":"2026-05-05T06:57:11.562278Z","end":"2026-05-05T06:57:11.959511Z","steps":["trace[2087862836] 'read index received' (duration: 159.731695ms)","trace[2087862836] 'applied index is now lower than readState.Index' (duration: 237.498004ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:11.959549Z","caller":"traceutil/trace.go:171","msg":"trace[1073516482] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"436.688375ms","start":"2026-05-05T06:57:11.522822Z","end":"2026-05-05T06:57:11.959511Z","steps":["trace[1073516482] 'process raft request' (duration: 358.653097ms)","trace[1073516482] 'compare' (duration: 77.857003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:11.9597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.522796Z","time spent":"436.823489ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:57:11.959844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.549589ms","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-05-05T06:57:11.959868Z","caller":"traceutil/trace.go:171","msg":"trace[672034931] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"434.366102ms","start":"2026-05-05T06:57:11.525478Z","end":"2026-05-05T06:57:11.959844Z","steps":["trace[672034931] 'process raft request' (duration: 433.972902ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.959906Z","caller":"traceutil/trace.go:171","msg":"trace[1851072729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5481; }","duration":"397.657242ms","start":"2026-05-05T06:57:11.562234Z","end":"2026-05-05T06:57:11.959891Z","steps":["trace[1851072729] 'agreement among raft nodes before linearized reading' (duration: 397.469697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.959939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.562215Z","time spent":"397.717733ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:57:11.95997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.892546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxf7s\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T06:57:11.960011Z","caller":"traceutil/trace.go:171","msg":"trace[77036382] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxf7s; range_end:; response_count:1; response_revision:5481; }","duration":"310.988869ms","start":"2026-05-05T06:57:11.649011Z","end":"2026-05-05T06:57:11.96Z","steps":["trace[77036382] 'agreement among raft nodes before linearized reading' (duration: 310.902526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.960041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.648993Z","time spent":"311.041029ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-jxf7s\" "} {"level":"warn","ts":"2026-05-05T06:57:11.960067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.582931ms","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-05-05T06:57:11.960057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.178407ms","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-05-05T06:57:11.960127Z","caller":"traceutil/trace.go:171","msg":"trace[1316439598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5481; }","duration":"317.27729ms","start":"2026-05-05T06:57:11.642834Z","end":"2026-05-05T06:57:11.960111Z","steps":["trace[1316439598] 'agreement among raft nodes before linearized reading' (duration: 317.091445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.960167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.642811Z","time spent":"317.346832ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:57:11.960144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.821641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:57:11.96021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.132069ms","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":"warn","ts":"2026-05-05T06:57:11.960231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.671079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-05-05T06:57:11.960258Z","caller":"traceutil/trace.go:171","msg":"trace[1439796355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5481; }","duration":"351.974606ms","start":"2026-05-05T06:57:11.608257Z","end":"2026-05-05T06:57:11.960231Z","steps":["trace[1439796355] 'agreement among raft nodes before linearized reading' (duration: 351.671558ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.960286Z","caller":"traceutil/trace.go:171","msg":"trace[562791297] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5481; }","duration":"150.7218ms","start":"2026-05-05T06:57:11.809547Z","end":"2026-05-05T06:57:11.960269Z","steps":["trace[562791297] 'agreement among raft nodes before linearized reading' (duration: 150.555105ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.960287Z","caller":"traceutil/trace.go:171","msg":"trace[505851935] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5481; }","duration":"126.232692ms","start":"2026-05-05T06:57:11.834031Z","end":"2026-05-05T06:57:11.960264Z","steps":["trace[505851935] 'agreement among raft nodes before linearized reading' (duration: 126.057717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.960328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.608236Z","time spent":"352.076808ms","remote":"127.0.0.1:40378","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-05-05T06:57:11.96003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.382501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:57:11.960423Z","caller":"traceutil/trace.go:171","msg":"trace[1176959311] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:5481; }","duration":"281.804083ms","start":"2026-05-05T06:57:11.678579Z","end":"2026-05-05T06:57:11.960383Z","steps":["trace[1176959311] 'agreement among raft nodes before linearized reading' (duration: 281.31202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.959889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.685413ms","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-05-05T06:57:11.960015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.525468Z","time spent":"434.460675ms","remote":"127.0.0.1:40378","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-05-05T06:57:11.960437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.058518ms","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-05-05T06:57:11.960501Z","caller":"traceutil/trace.go:171","msg":"trace[17071470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5481; }","duration":"394.356431ms","start":"2026-05-05T06:57:11.566133Z","end":"2026-05-05T06:57:11.960489Z","steps":["trace[17071470] 'agreement among raft nodes before linearized reading' (duration: 393.644381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.96056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.566108Z","time spent":"394.440563ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T06:57:11.960093Z","caller":"traceutil/trace.go:171","msg":"trace[2015954312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5481; }","duration":"389.622292ms","start":"2026-05-05T06:57:11.570465Z","end":"2026-05-05T06:57:11.960088Z","steps":["trace[2015954312] 'agreement among raft nodes before linearized reading' (duration: 389.55544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:11.960584Z","caller":"traceutil/trace.go:171","msg":"trace[1268914512] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5481; }","duration":"312.228862ms","start":"2026-05-05T06:57:11.648317Z","end":"2026-05-05T06:57:11.960546Z","steps":["trace[1268914512] 'agreement among raft nodes before linearized reading' (duration: 311.729959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.960676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.570459Z","time spent":"390.199597ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:57:11.96071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:11.648255Z","time spent":"312.436357ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:57:12.084039Z","caller":"traceutil/trace.go:171","msg":"trace[1681438752] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"117.984777ms","start":"2026-05-05T06:57:11.966026Z","end":"2026-05-05T06:57:12.084011Z","steps":["trace[1681438752] 'process raft request' (duration: 117.893554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:12.084285Z","caller":"traceutil/trace.go:171","msg":"trace[1195001722] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"118.07138ms","start":"2026-05-05T06:57:11.966201Z","end":"2026-05-05T06:57:12.084272Z","steps":["trace[1195001722] 'process raft request' (duration: 117.967647ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:12.084413Z","caller":"traceutil/trace.go:171","msg":"trace[1593988925] linearizableReadLoop","detail":"{readStateIndex:5686; appliedIndex:5682; }","duration":"117.878164ms","start":"2026-05-05T06:57:11.966523Z","end":"2026-05-05T06:57:12.084401Z","steps":["trace[1593988925] 'read index received' (duration: 88.211005ms)","trace[1593988925] 'applied index is now lower than readState.Index' (duration: 29.666099ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:12.084434Z","caller":"traceutil/trace.go:171","msg":"trace[1899462480] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"118.209613ms","start":"2026-05-05T06:57:11.966191Z","end":"2026-05-05T06:57:12.084401Z","steps":["trace[1899462480] 'process raft request' (duration: 117.777242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:12.084519Z","caller":"traceutil/trace.go:171","msg":"trace[1140614161] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"117.671858ms","start":"2026-05-05T06:57:11.966838Z","end":"2026-05-05T06:57:12.08451Z","steps":["trace[1140614161] 'process raft request' (duration: 117.391791ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:12.084652Z","caller":"traceutil/trace.go:171","msg":"trace[603191042] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"113.843904ms","start":"2026-05-05T06:57:11.970796Z","end":"2026-05-05T06:57:12.08464Z","steps":["trace[603191042] 'process raft request' (duration: 113.528405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:12.08468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.135031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81798"} {"level":"info","ts":"2026-05-05T06:57:12.084719Z","caller":"traceutil/trace.go:171","msg":"trace[470904282] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5488; }","duration":"118.199033ms","start":"2026-05-05T06:57:11.966509Z","end":"2026-05-05T06:57:12.084708Z","steps":["trace[470904282] 'agreement among raft nodes before linearized reading' (duration: 117.941726ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:12.084071Z","caller":"traceutil/trace.go:171","msg":"trace[628322852] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"118.10947ms","start":"2026-05-05T06:57:11.965909Z","end":"2026-05-05T06:57:12.084019Z","steps":["trace[628322852] 'process raft request' (duration: 117.860033ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:58:32.585299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3035} {"level":"info","ts":"2026-05-05T06:58:32.67106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3035,"took":"84.05904ms","hash":2089780951,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T06:58:32.671133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2089780951,"revision":3035,"compact-revision":-1} {"level":"info","ts":"2026-05-05T07:02:58.039051Z","caller":"traceutil/trace.go:171","msg":"trace[1638364099] linearizableReadLoop","detail":"{readStateIndex:8406; appliedIndex:8405; }","duration":"261.515254ms","start":"2026-05-05T07:02:57.777486Z","end":"2026-05-05T07:02:58.039001Z","steps":["trace[1638364099] 'read index received' (duration: 261.344799ms)","trace[1638364099] 'applied index is now lower than readState.Index' (duration: 169.225µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:02:58.03931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.806121ms","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-05-05T07:02:58.039377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.597007ms","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-05-05T07:02:58.039399Z","caller":"traceutil/trace.go:171","msg":"trace[1150118830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8122; }","duration":"261.904674ms","start":"2026-05-05T07:02:57.777472Z","end":"2026-05-05T07:02:58.039376Z","steps":["trace[1150118830] 'agreement among raft nodes before linearized reading' (duration: 261.665497ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:58.039419Z","caller":"traceutil/trace.go:171","msg":"trace[1133503673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8122; }","duration":"257.728331ms","start":"2026-05-05T07:02:57.78168Z","end":"2026-05-05T07:02:58.039409Z","steps":["trace[1133503673] 'agreement among raft nodes before linearized reading' (duration: 257.507525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:58.0395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.350642ms","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-05-05T07:02:58.039578Z","caller":"traceutil/trace.go:171","msg":"trace[1837386779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8122; }","duration":"256.480646ms","start":"2026-05-05T07:02:57.783081Z","end":"2026-05-05T07:02:58.039562Z","steps":["trace[1837386779] 'agreement among raft nodes before linearized reading' (duration: 256.241129ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:58.039716Z","caller":"traceutil/trace.go:171","msg":"trace[998241475] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"266.335146ms","start":"2026-05-05T07:02:57.773362Z","end":"2026-05-05T07:02:58.039697Z","steps":["trace[998241475] 'process raft request' (duration: 265.414721ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:58.191472Z","caller":"traceutil/trace.go:171","msg":"trace[125951108] linearizableReadLoop","detail":"{readStateIndex:8407; appliedIndex:8406; }","duration":"147.927655ms","start":"2026-05-05T07:02:58.043522Z","end":"2026-05-05T07:02:58.19145Z","steps":["trace[125951108] 'read index received' (duration: 147.899345ms)","trace[125951108] 'applied index is now lower than readState.Index' (duration: 27.24µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:02:58.191653Z","caller":"traceutil/trace.go:171","msg":"trace[477427953] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"148.212893ms","start":"2026-05-05T07:02:58.043418Z","end":"2026-05-05T07:02:58.191631Z","steps":["trace[477427953] 'process raft request' (duration: 147.895775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:58.19185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.314605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223819"} {"level":"info","ts":"2026-05-05T07:02:58.191894Z","caller":"traceutil/trace.go:171","msg":"trace[269624890] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8123; }","duration":"148.373577ms","start":"2026-05-05T07:02:58.04351Z","end":"2026-05-05T07:02:58.191884Z","steps":["trace[269624890] 'agreement among raft nodes before linearized reading' (duration: 148.032018ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:58.192759Z","caller":"traceutil/trace.go:171","msg":"trace[630127117] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"148.057989ms","start":"2026-05-05T07:02:58.04469Z","end":"2026-05-05T07:02:58.192748Z","steps":["trace[630127117] 'process raft request' (duration: 148.019848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:58.19288Z","caller":"traceutil/trace.go:171","msg":"trace[810637304] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"149.2123ms","start":"2026-05-05T07:02:58.043659Z","end":"2026-05-05T07:02:58.192872Z","steps":["trace[810637304] 'process raft request' (duration: 148.970603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:09.307411Z","caller":"traceutil/trace.go:171","msg":"trace[225400592] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"102.055382ms","start":"2026-05-05T07:03:09.205319Z","end":"2026-05-05T07:03:09.307375Z","steps":["trace[225400592] 'process raft request' (duration: 12.020297ms)","trace[225400592] 'compare' (duration: 89.886291ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:10.025996Z","caller":"traceutil/trace.go:171","msg":"trace[1314302651] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"339.558227ms","start":"2026-05-05T07:03:09.686412Z","end":"2026-05-05T07:03:10.02597Z","steps":["trace[1314302651] 'process raft request' (duration: 339.363902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:10.026147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:09.686375Z","time spent":"339.696241ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:03:10.030218Z","caller":"traceutil/trace.go:171","msg":"trace[759931904] linearizableReadLoop","detail":"{readStateIndex:8470; appliedIndex:8468; }","duration":"304.631036ms","start":"2026-05-05T07:03:09.725564Z","end":"2026-05-05T07:03:10.030195Z","steps":["trace[759931904] 'read index received' (duration: 300.292777ms)","trace[759931904] 'applied index is now lower than readState.Index' (duration: 4.336899ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:10.030324Z","caller":"traceutil/trace.go:171","msg":"trace[465708050] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"341.573742ms","start":"2026-05-05T07:03:09.688711Z","end":"2026-05-05T07:03:10.030284Z","steps":["trace[465708050] 'process raft request' (duration: 341.354686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:10.03041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.405375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:03:10.030448Z","caller":"traceutil/trace.go:171","msg":"trace[568149547] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8183; }","duration":"269.485447ms","start":"2026-05-05T07:03:09.760951Z","end":"2026-05-05T07:03:10.030437Z","steps":["trace[568149547] 'agreement among raft nodes before linearized reading' (duration: 269.423456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:10.030364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.77699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:03:10.030563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:09.688698Z","time spent":"341.756078ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:03:10.03061Z","caller":"traceutil/trace.go:171","msg":"trace[645699553] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8183; }","duration":"305.077507ms","start":"2026-05-05T07:03:09.72551Z","end":"2026-05-05T07:03:10.030588Z","steps":["trace[645699553] 'agreement among raft nodes before linearized reading' (duration: 304.80134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:10.030676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:09.725488Z","time spent":"305.16877ms","remote":"127.0.0.1:40744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-05-05T07:03:32.591814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6144} {"level":"info","ts":"2026-05-05T07:03:32.687088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6144,"took":"93.349175ms","hash":897833067,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":23322624,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T07:03:32.687144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":897833067,"revision":6144,"compact-revision":3035} {"level":"info","ts":"2026-05-05T07:03:37.38941Z","caller":"traceutil/trace.go:171","msg":"trace[1046366515] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"350.31777ms","start":"2026-05-05T07:03:37.039072Z","end":"2026-05-05T07:03:37.38939Z","steps":["trace[1046366515] 'process raft request' (duration: 350.199677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:37.389615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:37.039042Z","time spent":"350.502495ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:03:37.390113Z","caller":"traceutil/trace.go:171","msg":"trace[1740752717] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8681; }","duration":"301.10788ms","start":"2026-05-05T07:03:37.088993Z","end":"2026-05-05T07:03:37.390101Z","steps":["trace[1740752717] 'read index received' (duration: 301.10335ms)","trace[1740752717] 'applied index is now lower than readState.Index' (duration: 3.81µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:03:37.390672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.680545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:03:37.390821Z","caller":"traceutil/trace.go:171","msg":"trace[488977190] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8385; }","duration":"301.87017ms","start":"2026-05-05T07:03:37.088937Z","end":"2026-05-05T07:03:37.390808Z","steps":["trace[488977190] 'agreement among raft nodes before linearized reading' (duration: 301.694215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:37.39093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:37.088916Z","time spent":"302.002714ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-05T07:03:37.455842Z","caller":"traceutil/trace.go:171","msg":"trace[161482804] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"135.639818ms","start":"2026-05-05T07:03:37.320182Z","end":"2026-05-05T07:03:37.455822Z","steps":["trace[161482804] 'process raft request' (duration: 135.496104ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:37.456124Z","caller":"traceutil/trace.go:171","msg":"trace[825511377] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"111.655694ms","start":"2026-05-05T07:03:37.344442Z","end":"2026-05-05T07:03:37.456098Z","steps":["trace[825511377] 'process raft request' (duration: 111.352096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:37.456325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.063371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:03:37.456432Z","caller":"traceutil/trace.go:171","msg":"trace[1661479506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8387; }","duration":"194.218645ms","start":"2026-05-05T07:03:37.262188Z","end":"2026-05-05T07:03:37.456406Z","steps":["trace[1661479506] 'agreement among raft nodes before linearized reading' (duration: 193.979499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:49.48525Z","caller":"traceutil/trace.go:171","msg":"trace[2039967844] linearizableReadLoop","detail":"{readStateIndex:8792; appliedIndex:8791; }","duration":"143.78535ms","start":"2026-05-05T07:03:49.34144Z","end":"2026-05-05T07:03:49.485225Z","steps":["trace[2039967844] 'read index received' (duration: 133.693975ms)","trace[2039967844] 'applied index is now lower than readState.Index' (duration: 10.090225ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:49.485352Z","caller":"traceutil/trace.go:171","msg":"trace[1041550294] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8491; }","duration":"161.967526ms","start":"2026-05-05T07:03:49.323366Z","end":"2026-05-05T07:03:49.485334Z","steps":["trace[1041550294] 'process raft request' (duration: 151.757578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:49.485576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.128429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7qlw2\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-05-05T07:03:49.485658Z","caller":"traceutil/trace.go:171","msg":"trace[1633265698] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7qlw2; range_end:; response_count:1; response_revision:8491; }","duration":"144.269743ms","start":"2026-05-05T07:03:49.341371Z","end":"2026-05-05T07:03:49.485641Z","steps":["trace[1633265698] 'agreement among raft nodes before linearized reading' (duration: 144.040317ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:39.075822Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-05-05T07:05:01.868041Z","caller":"traceutil/trace.go:171","msg":"trace[1057671652] transaction","detail":"{read_only:false; response_revision:9158; number_of_response:1; }","duration":"121.262676ms","start":"2026-05-05T07:05:01.746752Z","end":"2026-05-05T07:05:01.868014Z","steps":["trace[1057671652] 'process raft request' (duration: 121.073201ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:01.968942Z","caller":"traceutil/trace.go:171","msg":"trace[1136186487] transaction","detail":"{read_only:false; response_revision:9159; number_of_response:1; }","duration":"221.673444ms","start":"2026-05-05T07:05:01.74721Z","end":"2026-05-05T07:05:01.968884Z","steps":["trace[1136186487] 'process raft request' (duration: 211.61869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:01.969286Z","caller":"traceutil/trace.go:171","msg":"trace[705960854] transaction","detail":"{read_only:false; response_revision:9160; number_of_response:1; }","duration":"220.708258ms","start":"2026-05-05T07:05:01.748545Z","end":"2026-05-05T07:05:01.969253Z","steps":["trace[705960854] 'process raft request' (duration: 220.276156ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:57.153519Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:05:57.159699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T07:05:57.159803Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T07:08:32.597383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8316} {"level":"info","ts":"2026-05-05T07:08:32.674456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8316,"took":"74.433905ms","hash":3946209678,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":26836992,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T07:08:32.674523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3946209678,"revision":8316,"compact-revision":6144} {"level":"info","ts":"2026-05-05T07:08:39.815851Z","caller":"traceutil/trace.go:171","msg":"trace[630937231] transaction","detail":"{read_only:false; response_revision:11076; number_of_response:1; }","duration":"131.571177ms","start":"2026-05-05T07:08:39.684244Z","end":"2026-05-05T07:08:39.815815Z","steps":["trace[630937231] 'process raft request' (duration: 131.30243ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:39.919909Z","caller":"traceutil/trace.go:171","msg":"trace[502784127] transaction","detail":"{read_only:false; response_revision:11078; number_of_response:1; }","duration":"232.630619ms","start":"2026-05-05T07:08:39.687254Z","end":"2026-05-05T07:08:39.919884Z","steps":["trace[502784127] 'process raft request' (duration: 232.515786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:39.919896Z","caller":"traceutil/trace.go:171","msg":"trace[833385457] transaction","detail":"{read_only:false; response_revision:11077; number_of_response:1; }","duration":"234.560532ms","start":"2026-05-05T07:08:39.685298Z","end":"2026-05-05T07:08:39.919858Z","steps":["trace[833385457] 'process raft request' (duration: 208.785798ms)","trace[833385457] 'compare' (duration: 25.448205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:08:41.45204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.55194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxf7s\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T07:08:41.45215Z","caller":"traceutil/trace.go:171","msg":"trace[60295134] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxf7s; range_end:; response_count:1; response_revision:11082; }","duration":"978.709184ms","start":"2026-05-05T07:08:40.473413Z","end":"2026-05-05T07:08:41.452122Z","steps":["trace[60295134] 'range keys from in-memory index tree' (duration: 978.18787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.452243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:40.473392Z","time spent":"978.813037ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-jxf7s\" "} {"level":"info","ts":"2026-05-05T07:08:41.459865Z","caller":"traceutil/trace.go:171","msg":"trace[2133508882] transaction","detail":"{read_only:false; response_revision:11083; number_of_response:1; }","duration":"876.947343ms","start":"2026-05-05T07:08:40.582895Z","end":"2026-05-05T07:08:41.459842Z","steps":["trace[2133508882] 'process raft request' (duration: 876.810069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.46001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:40.582869Z","time spent":"877.056765ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:08:41.515023Z","caller":"traceutil/trace.go:171","msg":"trace[1227875099] transaction","detail":"{read_only:false; response_revision:11084; number_of_response:1; }","duration":"726.004886ms","start":"2026-05-05T07:08:40.788994Z","end":"2026-05-05T07:08:41.514941Z","steps":["trace[1227875099] 'process raft request' (duration: 725.758329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515167Z","caller":"traceutil/trace.go:171","msg":"trace[226691279] linearizableReadLoop","detail":"{readStateIndex:11464; appliedIndex:11461; }","duration":"290.182933ms","start":"2026-05-05T07:08:41.224955Z","end":"2026-05-05T07:08:41.515138Z","steps":["trace[226691279] 'read index received' (duration: 235.381835ms)","trace[226691279] 'applied index is now lower than readState.Index' (duration: 54.800118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:08:41.515198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:40.788968Z","time spent":"726.13404ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:08:41.515191Z","caller":"traceutil/trace.go:171","msg":"trace[363388755] transaction","detail":"{read_only:false; response_revision:11085; number_of_response:1; }","duration":"725.603685ms","start":"2026-05-05T07:08:40.789557Z","end":"2026-05-05T07:08:41.51516Z","steps":["trace[363388755] 'process raft request' (duration: 725.339588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.515317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:40.789547Z","time spent":"725.708878ms","remote":"127.0.0.1:40378","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-05-05T07:08:41.515337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.107571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:41.515372Z","caller":"traceutil/trace.go:171","msg":"trace[951709661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11085; }","duration":"252.150213ms","start":"2026-05-05T07:08:41.263211Z","end":"2026-05-05T07:08:41.515361Z","steps":["trace[951709661] 'agreement among raft nodes before linearized reading' (duration: 252.080871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.515396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.419829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T07:08:41.515481Z","caller":"traceutil/trace.go:171","msg":"trace[1735330904] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:11085; }","duration":"290.511181ms","start":"2026-05-05T07:08:41.22495Z","end":"2026-05-05T07:08:41.515461Z","steps":["trace[1735330904] 'agreement among raft nodes before linearized reading' (duration: 290.301036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.515408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.055203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:08:41.515644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.926228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:08:41.515653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.235375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-05-05T07:08:41.51571Z","caller":"traceutil/trace.go:171","msg":"trace[371273683] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11085; }","duration":"165.071993ms","start":"2026-05-05T07:08:41.350623Z","end":"2026-05-05T07:08:41.515694Z","steps":["trace[371273683] 'agreement among raft nodes before linearized reading' (duration: 164.96401ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515696Z","caller":"traceutil/trace.go:171","msg":"trace[1692234782] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11085; }","duration":"112.372101ms","start":"2026-05-05T07:08:41.403312Z","end":"2026-05-05T07:08:41.515685Z","steps":["trace[1692234782] 'agreement among raft nodes before linearized reading' (duration: 112.034612ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515729Z","caller":"traceutil/trace.go:171","msg":"trace[1281577514] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11085; }","duration":"162.320517ms","start":"2026-05-05T07:08:41.353387Z","end":"2026-05-05T07:08:41.515707Z","steps":["trace[1281577514] 'agreement among raft nodes before linearized reading' (duration: 162.111841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.51569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.113816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-05-05T07:08:41.515777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.003073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:08:41.515807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.64075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:41.515847Z","caller":"traceutil/trace.go:171","msg":"trace[24425445] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11085; }","duration":"164.112106ms","start":"2026-05-05T07:08:41.351717Z","end":"2026-05-05T07:08:41.515829Z","steps":["trace[24425445] 'agreement among raft nodes before linearized reading' (duration: 164.018434ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515847Z","caller":"traceutil/trace.go:171","msg":"trace[1903153084] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11085; }","duration":"164.283701ms","start":"2026-05-05T07:08:41.351537Z","end":"2026-05-05T07:08:41.51582Z","steps":["trace[1903153084] 'agreement among raft nodes before linearized reading' (duration: 163.935782ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515879Z","caller":"traceutil/trace.go:171","msg":"trace[1966302133] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11085; }","duration":"164.728843ms","start":"2026-05-05T07:08:41.351132Z","end":"2026-05-05T07:08:41.515861Z","steps":["trace[1966302133] 'agreement among raft nodes before linearized reading' (duration: 164.60615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.515883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.291323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:08:41.515653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.582899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:08:41.515966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.090407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-05-05T07:08:41.516001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.653373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:41.516018Z","caller":"traceutil/trace.go:171","msg":"trace[1508195081] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11085; }","duration":"111.191759ms","start":"2026-05-05T07:08:41.404815Z","end":"2026-05-05T07:08:41.516007Z","steps":["trace[1508195081] 'agreement among raft nodes before linearized reading' (duration: 111.039155ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.516083Z","caller":"traceutil/trace.go:171","msg":"trace[276263511] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11085; }","duration":"111.751515ms","start":"2026-05-05T07:08:41.404309Z","end":"2026-05-05T07:08:41.51606Z","steps":["trace[276263511] 'agreement among raft nodes before linearized reading' (duration: 111.624781ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.515933Z","caller":"traceutil/trace.go:171","msg":"trace[691655618] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11085; }","duration":"111.382325ms","start":"2026-05-05T07:08:41.404537Z","end":"2026-05-05T07:08:41.51592Z","steps":["trace[691655618] 'agreement among raft nodes before linearized reading' (duration: 111.311543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:41.516045Z","caller":"traceutil/trace.go:171","msg":"trace[273672471] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11085; }","duration":"164.957599ms","start":"2026-05-05T07:08:41.35102Z","end":"2026-05-05T07:08:41.515978Z","steps":["trace[273672471] 'agreement among raft nodes before linearized reading' (duration: 164.563759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:41.517492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.909197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:41.517576Z","caller":"traceutil/trace.go:171","msg":"trace[240862327] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11085; }","duration":"113.074221ms","start":"2026-05-05T07:08:41.404484Z","end":"2026-05-05T07:08:41.517558Z","steps":["trace[240862327] 'agreement among raft nodes before linearized reading' (duration: 111.408205ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:42.673093Z","caller":"traceutil/trace.go:171","msg":"trace[173906300] transaction","detail":"{read_only:false; response_revision:11092; number_of_response:1; }","duration":"225.75888ms","start":"2026-05-05T07:08:42.447275Z","end":"2026-05-05T07:08:42.673034Z","steps":["trace[173906300] 'process raft request' (duration: 223.998982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:44.367688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.388633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:44.367785Z","caller":"traceutil/trace.go:171","msg":"trace[1148265822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11102; }","duration":"104.566468ms","start":"2026-05-05T07:08:44.263199Z","end":"2026-05-05T07:08:44.367765Z","steps":["trace[1148265822] 'range keys from in-memory index tree' (duration: 104.318292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:44.367847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.319016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/cinder-db-sync-hkfkw\" ","response":"range_response_count:1 size:2756"} {"level":"info","ts":"2026-05-05T07:08:44.36793Z","caller":"traceutil/trace.go:171","msg":"trace[2096007499] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/cinder-db-sync-hkfkw; range_end:; response_count:1; response_revision:11102; }","duration":"204.428598ms","start":"2026-05-05T07:08:44.163467Z","end":"2026-05-05T07:08:44.367896Z","steps":["trace[2096007499] 'range keys from in-memory index tree' (duration: 204.188501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:44.367917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.701733ms","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-05-05T07:08:44.368009Z","caller":"traceutil/trace.go:171","msg":"trace[1570932484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11102; }","duration":"370.835207ms","start":"2026-05-05T07:08:43.997149Z","end":"2026-05-05T07:08:44.367984Z","steps":["trace[1570932484] 'range keys from in-memory index tree' (duration: 370.537429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:44.368058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:43.997137Z","time spent":"370.907379ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:08:44.36826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.270527ms","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":"warn","ts":"2026-05-05T07:08:44.368278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.802996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:08:44.36829Z","caller":"traceutil/trace.go:171","msg":"trace[1198651166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11102; }","duration":"349.314799ms","start":"2026-05-05T07:08:44.018965Z","end":"2026-05-05T07:08:44.36828Z","steps":["trace[1198651166] 'range keys from in-memory index tree' (duration: 349.210166ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:44.368306Z","caller":"traceutil/trace.go:171","msg":"trace[1037716048] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11102; }","duration":"231.874209ms","start":"2026-05-05T07:08:44.136422Z","end":"2026-05-05T07:08:44.368297Z","steps":["trace[1037716048] 'range keys from in-memory index tree' (duration: 231.694503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:44.368314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:08:44.018958Z","time spent":"349.35134ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T07:13:32.604795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11037} {"level":"info","ts":"2026-05-05T07:13:32.682496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11037,"took":"74.529447ms","hash":346111231,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":28172288,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T07:13:32.682564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":346111231,"revision":11037,"compact-revision":8316} {"level":"info","ts":"2026-05-05T07:18:32.611534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13073} {"level":"info","ts":"2026-05-05T07:18:32.684609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13073,"took":"70.861855ms","hash":1369391068,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":23990272,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T07:18:32.68466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1369391068,"revision":13073,"compact-revision":11037} {"level":"info","ts":"2026-05-05T07:23:32.618335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14709} {"level":"info","ts":"2026-05-05T07:23:32.689918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14709,"took":"69.098945ms","hash":2650408428,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20369408,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:23:32.689992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2650408428,"revision":14709,"compact-revision":13073} {"level":"info","ts":"2026-05-05T07:28:32.624952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16235} {"level":"info","ts":"2026-05-05T07:28:32.691062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16235,"took":"63.272598ms","hash":717773630,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20307968,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:28:32.691139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":717773630,"revision":16235,"compact-revision":14709} {"level":"info","ts":"2026-05-05T07:33:32.631451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17756} {"level":"info","ts":"2026-05-05T07:33:32.698695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17756,"took":"64.933973ms","hash":2064046900,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20283392,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:33:32.698766Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2064046900,"revision":17756,"compact-revision":16235} {"level":"info","ts":"2026-05-05T07:33:35.78629Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:33:35.791518Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T07:33:35.791643Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T07:38:32.638476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19278} {"level":"info","ts":"2026-05-05T07:38:32.70461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19278,"took":"64.106942ms","hash":89641987,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20426752,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:38:32.704692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":89641987,"revision":19278,"compact-revision":17756} {"level":"info","ts":"2026-05-05T07:43:32.645107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20825} {"level":"info","ts":"2026-05-05T07:43:32.713583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20825,"took":"65.317616ms","hash":2474334388,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20348928,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:43:32.713651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2474334388,"revision":20825,"compact-revision":19278} {"level":"info","ts":"2026-05-05T07:44:54.984911Z","caller":"traceutil/trace.go:171","msg":"trace[464266548] transaction","detail":"{read_only:false; response_revision:22765; number_of_response:1; }","duration":"136.723419ms","start":"2026-05-05T07:44:54.848151Z","end":"2026-05-05T07:44:54.984875Z","steps":["trace[464266548] 'process raft request' (duration: 133.270224ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:44:54.985668Z","caller":"traceutil/trace.go:171","msg":"trace[336787165] transaction","detail":"{read_only:false; response_revision:22766; number_of_response:1; }","duration":"136.325758ms","start":"2026-05-05T07:44:54.849284Z","end":"2026-05-05T07:44:54.985609Z","steps":["trace[336787165] 'process raft request' (duration: 136.149683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:48:32.651846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22349} {"level":"info","ts":"2026-05-05T07:48:32.715926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22349,"took":"61.764199ms","hash":2477806195,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20320256,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:48:32.715987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2477806195,"revision":22349,"compact-revision":20825} {"level":"info","ts":"2026-05-05T07:53:32.658554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23869} {"level":"info","ts":"2026-05-05T07:53:32.730213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23869,"took":"67.918406ms","hash":671087713,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":20447232,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:53:32.730338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":671087713,"revision":23869,"compact-revision":22349}