{"level":"warn","ts":"2026-07-01T10:35:40.240823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:35:40.24099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T10:35:40.241127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:35:40.241168Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T10:35:40.241211Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T10:35:40.242036Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-01T10:35:40.242205Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T10:35:40.255309Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.806262ms"} {"level":"info","ts":"2026-07-01T10:35:40.266227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-01T10:35:40.26635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T10:35:40.266393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-01T10:35:40.266428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T10:35:40.266458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-01T10:35:40.26652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-01T10:35:40.273056Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T10:35:40.274499Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T10:35:40.275764Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T10:35:40.277948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T10:35:40.278269Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T10:35:40.278324Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:35:40.278484Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:35:40.27851Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T10:35:40.278926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-01T10:35:40.279618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T10:35:40.286464Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T10:35:40.286593Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T10:35:40.286669Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T10:35:40.286975Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T10:35:40.287036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T10:35:40.667604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T10:35:40.667672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T10:35:40.667705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-01T10:35:40.667729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-01T10:35:40.667742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T10:35:40.667787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-01T10:35:40.667807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T10:35:40.669438Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T10:35:40.669874Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:35:40.670503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:35:40.670604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:35:40.670678Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T10:35:40.670774Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T10:35:40.672488Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-01T10:35:40.673818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T10:35:40.685974Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:35:40.686103Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:35:40.686137Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:35:51.685609Z","caller":"traceutil/trace.go:171","msg":"trace[1615861997] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"263.226482ms","start":"2026-07-01T10:35:51.422355Z","end":"2026-07-01T10:35:51.685582Z","steps":["trace[1615861997] 'process raft request' (duration: 262.919846ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:35:51.899114Z","caller":"traceutil/trace.go:171","msg":"trace[238889428] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"105.957977ms","start":"2026-07-01T10:35:51.793115Z","end":"2026-07-01T10:35:51.899073Z","steps":["trace[238889428] 'read index received' (duration: 103.97596ms)","trace[238889428] 'applied index is now lower than readState.Index' (duration: 1.980667ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:35:51.899181Z","caller":"traceutil/trace.go:171","msg":"trace[69047860] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"201.051251ms","start":"2026-07-01T10:35:51.698088Z","end":"2026-07-01T10:35:51.899139Z","steps":["trace[69047860] 'process raft request' (duration: 198.973811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:35:51.899286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.151632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T10:35:51.899382Z","caller":"traceutil/trace.go:171","msg":"trace[1085160300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"106.289814ms","start":"2026-07-01T10:35:51.793073Z","end":"2026-07-01T10:35:51.899363Z","steps":["trace[1085160300] 'agreement among raft nodes before linearized reading' (duration: 106.123731ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:23.745203Z","caller":"traceutil/trace.go:171","msg":"trace[1951070146] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"168.87897ms","start":"2026-07-01T10:36:23.576297Z","end":"2026-07-01T10:36:23.745176Z","steps":["trace[1951070146] 'process raft request' (duration: 168.676586ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:23.750271Z","caller":"traceutil/trace.go:171","msg":"trace[1778767028] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"110.422749ms","start":"2026-07-01T10:36:23.639826Z","end":"2026-07-01T10:36:23.750249Z","steps":["trace[1778767028] 'process raft request' (duration: 110.229375ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:24.488559Z","caller":"traceutil/trace.go:171","msg":"trace[938510359] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"158.781781ms","start":"2026-07-01T10:36:24.329736Z","end":"2026-07-01T10:36:24.488518Z","steps":["trace[938510359] 'read index received' (duration: 157.407025ms)","trace[938510359] 'applied index is now lower than readState.Index' (duration: 1.373176ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:36:24.488625Z","caller":"traceutil/trace.go:171","msg":"trace[1123206239] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"270.069676ms","start":"2026-07-01T10:36:24.218514Z","end":"2026-07-01T10:36:24.488583Z","steps":["trace[1123206239] 'process raft request' (duration: 269.047177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:24.48882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.051756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T10:36:24.488874Z","caller":"traceutil/trace.go:171","msg":"trace[940467078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"159.165847ms","start":"2026-07-01T10:36:24.329694Z","end":"2026-07-01T10:36:24.48886Z","steps":["trace[940467078] 'agreement among raft nodes before linearized reading' (duration: 158.956253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:24.9301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.979557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:36:24.930183Z","caller":"traceutil/trace.go:171","msg":"trace[1324017412] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"331.640294ms","start":"2026-07-01T10:36:24.598531Z","end":"2026-07-01T10:36:24.930172Z","steps":["trace[1324017412] 'read index received' (duration: 140.022675ms)","trace[1324017412] 'applied index is now lower than readState.Index' (duration: 191.616639ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:36:24.930209Z","caller":"traceutil/trace.go:171","msg":"trace[138461149] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"382.733026ms","start":"2026-07-01T10:36:24.547454Z","end":"2026-07-01T10:36:24.930187Z","steps":["trace[138461149] 'process raft request' (duration: 191.082207ms)","trace[138461149] 'compare' (duration: 190.742582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:36:24.930296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.771547ms","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-07-01T10:36:24.930319Z","caller":"traceutil/trace.go:171","msg":"trace[1322724128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"331.837448ms","start":"2026-07-01T10:36:24.598476Z","end":"2026-07-01T10:36:24.930313Z","steps":["trace[1322724128] 'agreement among raft nodes before linearized reading' (duration: 331.726536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:24.930303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:36:24.547426Z","time spent":"382.833538ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:36:24.930345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:36:24.598451Z","time spent":"331.889679ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T10:36:26.040569Z","caller":"traceutil/trace.go:171","msg":"trace[1189578944] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"152.581644ms","start":"2026-07-01T10:36:25.887958Z","end":"2026-07-01T10:36:26.04054Z","steps":["trace[1189578944] 'read index received' (duration: 150.615447ms)","trace[1189578944] 'applied index is now lower than readState.Index' (duration: 1.964746ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:36:26.040629Z","caller":"traceutil/trace.go:171","msg":"trace[276093277] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"153.067042ms","start":"2026-07-01T10:36:25.887531Z","end":"2026-07-01T10:36:26.040598Z","steps":["trace[276093277] 'process raft request' (duration: 151.131856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.040898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.76787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:36:26.040725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.744166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:36:26.040961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.93127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.040985Z","caller":"traceutil/trace.go:171","msg":"trace[230824068] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"153.018221ms","start":"2026-07-01T10:36:25.887942Z","end":"2026-07-01T10:36:26.04096Z","steps":["trace[230824068] 'agreement among raft nodes before linearized reading' (duration: 152.718486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.041003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.042183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.041019Z","caller":"traceutil/trace.go:171","msg":"trace[896309248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"152.984461ms","start":"2026-07-01T10:36:25.888019Z","end":"2026-07-01T10:36:26.041003Z","steps":["trace[896309248] 'agreement among raft nodes before linearized reading' (duration: 152.774647ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:26.041052Z","caller":"traceutil/trace.go:171","msg":"trace[776839905] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"151.084284ms","start":"2026-07-01T10:36:25.889954Z","end":"2026-07-01T10:36:26.041039Z","steps":["trace[776839905] 'agreement among raft nodes before linearized reading' (duration: 151.033303ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:26.040964Z","caller":"traceutil/trace.go:171","msg":"trace[1940732881] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"122.861913ms","start":"2026-07-01T10:36:25.91808Z","end":"2026-07-01T10:36:26.040942Z","steps":["trace[1940732881] 'agreement among raft nodes before linearized reading' (duration: 122.764151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.040903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.506493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.041181Z","caller":"traceutil/trace.go:171","msg":"trace[574670904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"151.792159ms","start":"2026-07-01T10:36:25.889378Z","end":"2026-07-01T10:36:26.04117Z","steps":["trace[574670904] 'agreement among raft nodes before linearized reading' (duration: 151.456412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.040968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.869753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.041287Z","caller":"traceutil/trace.go:171","msg":"trace[647684945] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"149.19463ms","start":"2026-07-01T10:36:25.892081Z","end":"2026-07-01T10:36:26.041275Z","steps":["trace[647684945] 'agreement among raft nodes before linearized reading' (duration: 148.869053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.041323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.122664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:36:26.041348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.220736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:36:26.041347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.237436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.041391Z","caller":"traceutil/trace.go:171","msg":"trace[967546479] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"153.305168ms","start":"2026-07-01T10:36:25.888073Z","end":"2026-07-01T10:36:26.041378Z","steps":["trace[967546479] 'agreement among raft nodes before linearized reading' (duration: 153.213416ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:26.04139Z","caller":"traceutil/trace.go:171","msg":"trace[1539812785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"153.217046ms","start":"2026-07-01T10:36:25.888156Z","end":"2026-07-01T10:36:26.041373Z","steps":["trace[1539812785] 'agreement among raft nodes before linearized reading' (duration: 153.100243ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:36:26.041409Z","caller":"traceutil/trace.go:171","msg":"trace[656514167] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"153.339078ms","start":"2026-07-01T10:36:25.88805Z","end":"2026-07-01T10:36:26.041389Z","steps":["trace[656514167] 'agreement among raft nodes before linearized reading' (duration: 153.230366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:26.041471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.173335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:26.041564Z","caller":"traceutil/trace.go:171","msg":"trace[453414855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"153.375359ms","start":"2026-07-01T10:36:25.888175Z","end":"2026-07-01T10:36:26.04155Z","steps":["trace[453414855] 'agreement among raft nodes before linearized reading' (duration: 153.149005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:36:54.198988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.952885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:36:54.199104Z","caller":"traceutil/trace.go:171","msg":"trace[1383954100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"165.115448ms","start":"2026-07-01T10:36:54.03396Z","end":"2026-07-01T10:36:54.199076Z","steps":["trace[1383954100] 'range keys from in-memory index tree' (duration: 164.862624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:38:02.760311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.819778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T10:38:02.760433Z","caller":"traceutil/trace.go:171","msg":"trace[585396638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1884; }","duration":"147.975622ms","start":"2026-07-01T10:38:02.612436Z","end":"2026-07-01T10:38:02.760411Z","steps":["trace[585396638] 'range keys from in-memory index tree' (duration: 147.672916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:38:02.760412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.667103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T10:38:02.760511Z","caller":"traceutil/trace.go:171","msg":"trace[1005816075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1884; }","duration":"194.818275ms","start":"2026-07-01T10:38:02.565671Z","end":"2026-07-01T10:38:02.76049Z","steps":["trace[1005816075] 'range keys from in-memory index tree' (duration: 194.430908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:38:03.300673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.727241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-01T10:38:03.300841Z","caller":"traceutil/trace.go:171","msg":"trace[452000619] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"111.903494ms","start":"2026-07-01T10:38:03.188867Z","end":"2026-07-01T10:38:03.30077Z","steps":["trace[452000619] 'agreement among raft nodes before linearized reading' (duration: 111.641299ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:11.357347Z","caller":"traceutil/trace.go:171","msg":"trace[462893541] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"152.134047ms","start":"2026-07-01T10:44:11.20519Z","end":"2026-07-01T10:44:11.357324Z","steps":["trace[462893541] 'read index received' (duration: 151.933074ms)","trace[462893541] 'applied index is now lower than readState.Index' (duration: 200.113µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:44:11.357469Z","caller":"traceutil/trace.go:171","msg":"trace[1652405560] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"156.573642ms","start":"2026-07-01T10:44:11.200886Z","end":"2026-07-01T10:44:11.357459Z","steps":["trace[1652405560] 'process raft request' (duration: 156.299137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:11.357735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.513074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-01T10:44:11.357837Z","caller":"traceutil/trace.go:171","msg":"trace[1956438838] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5044; }","duration":"152.659287ms","start":"2026-07-01T10:44:11.205152Z","end":"2026-07-01T10:44:11.357811Z","steps":["trace[1956438838] 'agreement among raft nodes before linearized reading' (duration: 152.382232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:15.442579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.206427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:44:15.442784Z","caller":"traceutil/trace.go:171","msg":"trace[1862769863] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"223.031673ms","start":"2026-07-01T10:44:15.219726Z","end":"2026-07-01T10:44:15.442758Z","steps":["trace[1862769863] 'process raft request' (duration: 117.374487ms)","trace[1862769863] 'compare' (duration: 104.998183ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:44:15.731162Z","caller":"traceutil/trace.go:171","msg":"trace[1711286271] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"283.055421ms","start":"2026-07-01T10:44:15.448071Z","end":"2026-07-01T10:44:15.731126Z","steps":["trace[1711286271] 'process raft request' (duration: 233.152085ms)","trace[1711286271] 'compare' (duration: 49.518679ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:44:15.73126Z","caller":"traceutil/trace.go:171","msg":"trace[1272825204] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"282.194715ms","start":"2026-07-01T10:44:15.449032Z","end":"2026-07-01T10:44:15.731226Z","steps":["trace[1272825204] 'process raft request' (duration: 281.99223ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.731544Z","caller":"traceutil/trace.go:171","msg":"trace[970814363] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"282.43446ms","start":"2026-07-01T10:44:15.449081Z","end":"2026-07-01T10:44:15.731516Z","steps":["trace[970814363] 'process raft request' (duration: 282.070384ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.731625Z","caller":"traceutil/trace.go:171","msg":"trace[2098952471] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"282.091434ms","start":"2026-07-01T10:44:15.449506Z","end":"2026-07-01T10:44:15.731597Z","steps":["trace[2098952471] 'process raft request' (duration: 281.837219ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.731533Z","caller":"traceutil/trace.go:171","msg":"trace[328954635] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5313; }","duration":"272.98193ms","start":"2026-07-01T10:44:15.458514Z","end":"2026-07-01T10:44:15.731496Z","steps":["trace[328954635] 'read index received' (duration: 222.683026ms)","trace[328954635] 'applied index is now lower than readState.Index' (duration: 50.297034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:44:15.732125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.106366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-01T10:44:15.732119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.916774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9pbzq\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-07-01T10:44:15.732217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.680494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:797096"} {"level":"warn","ts":"2026-07-01T10:44:15.732301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.793414ms","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-07-01T10:44:15.732344Z","caller":"traceutil/trace.go:171","msg":"trace[859184168] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:5126; }","duration":"273.860346ms","start":"2026-07-01T10:44:15.458469Z","end":"2026-07-01T10:44:15.73233Z","steps":["trace[859184168] 'agreement among raft nodes before linearized reading' (duration: 273.131063ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.732351Z","caller":"traceutil/trace.go:171","msg":"trace[341418629] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5126; }","duration":"186.931686ms","start":"2026-07-01T10:44:15.545404Z","end":"2026-07-01T10:44:15.732335Z","steps":["trace[341418629] 'agreement among raft nodes before linearized reading' (duration: 186.690842ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.732276Z","caller":"traceutil/trace.go:171","msg":"trace[1896934275] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9pbzq; range_end:; response_count:1; response_revision:5126; }","duration":"141.057726ms","start":"2026-07-01T10:44:15.59114Z","end":"2026-07-01T10:44:15.732197Z","steps":["trace[1896934275] 'agreement among raft nodes before linearized reading' (duration: 140.783831ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:15.732211Z","caller":"traceutil/trace.go:171","msg":"trace[1169792525] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5126; }","duration":"270.225818ms","start":"2026-07-01T10:44:15.46196Z","end":"2026-07-01T10:44:15.732185Z","steps":["trace[1169792525] 'agreement among raft nodes before linearized reading' (duration: 269.933432ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:37.645733Z","caller":"traceutil/trace.go:171","msg":"trace[1234751445] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"151.996414ms","start":"2026-07-01T10:44:37.493688Z","end":"2026-07-01T10:44:37.645684Z","steps":["trace[1234751445] 'process raft request' (duration: 151.667988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:37.645966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.15762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-01T10:44:37.646119Z","caller":"traceutil/trace.go:171","msg":"trace[846510176] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5530; }","duration":"100.328884ms","start":"2026-07-01T10:44:37.545748Z","end":"2026-07-01T10:44:37.646077Z","steps":["trace[846510176] 'agreement among raft nodes before linearized reading' (duration: 99.878595ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:37.77134Z","caller":"traceutil/trace.go:171","msg":"trace[1675064901] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"120.180681ms","start":"2026-07-01T10:44:37.651132Z","end":"2026-07-01T10:44:37.771312Z","steps":["trace[1675064901] 'process raft request' (duration: 39.162554ms)","trace[1675064901] 'compare' (duration: 80.901975ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:44:37.771335Z","caller":"traceutil/trace.go:171","msg":"trace[1191885233] linearizableReadLoop","detail":"{readStateIndex:5733; appliedIndex:5732; }","duration":"118.350577ms","start":"2026-07-01T10:44:37.652946Z","end":"2026-07-01T10:44:37.771296Z","steps":["trace[1191885233] 'read index received' (duration: 37.35907ms)","trace[1191885233] 'applied index is now lower than readState.Index' (duration: 80.990307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:44:37.77164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.54398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-01T10:44:37.771753Z","caller":"traceutil/trace.go:171","msg":"trace[1876351011] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5531; }","duration":"118.810485ms","start":"2026-07-01T10:44:37.652921Z","end":"2026-07-01T10:44:37.771731Z","steps":["trace[1876351011] 'agreement among raft nodes before linearized reading' (duration: 118.468589ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:37.802509Z","caller":"traceutil/trace.go:171","msg":"trace[728327343] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"142.426682ms","start":"2026-07-01T10:44:37.660061Z","end":"2026-07-01T10:44:37.802488Z","steps":["trace[728327343] 'process raft request' (duration: 142.263999ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:38.310212Z","caller":"traceutil/trace.go:171","msg":"trace[867510545] linearizableReadLoop","detail":"{readStateIndex:5735; appliedIndex:5734; }","duration":"466.892351ms","start":"2026-07-01T10:44:37.843299Z","end":"2026-07-01T10:44:38.310191Z","steps":["trace[867510545] 'read index received' (duration: 466.81832ms)","trace[867510545] 'applied index is now lower than readState.Index' (duration: 73.101µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:44:38.310304Z","caller":"traceutil/trace.go:171","msg":"trace[1078914560] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"490.6182ms","start":"2026-07-01T10:44:37.819676Z","end":"2026-07-01T10:44:38.310295Z","steps":["trace[1078914560] 'process raft request' (duration: 490.366875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.310393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:37.81964Z","time spent":"490.685031ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17217,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:44:38.310567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.277378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9pbzq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-01T10:44:38.310588Z","caller":"traceutil/trace.go:171","msg":"trace[757609655] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9pbzq; range_end:; response_count:1; response_revision:5533; }","duration":"467.332729ms","start":"2026-07-01T10:44:37.843249Z","end":"2026-07-01T10:44:38.310582Z","steps":["trace[757609655] 'agreement among raft nodes before linearized reading' (duration: 467.241997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.310607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:37.843231Z","time spent":"467.371639ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-9pbzq\" "} {"level":"warn","ts":"2026-07-01T10:44:38.70011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.138244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:44:38.700238Z","caller":"traceutil/trace.go:171","msg":"trace[331711002] linearizableReadLoop","detail":"{readStateIndex:5736; appliedIndex:5735; }","duration":"389.93191ms","start":"2026-07-01T10:44:38.310266Z","end":"2026-07-01T10:44:38.700198Z","steps":["trace[331711002] 'read index received' (duration: 117.51105ms)","trace[331711002] 'applied index is now lower than readState.Index' (duration: 272.4195ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:44:38.700337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.163821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:44:38.700415Z","caller":"traceutil/trace.go:171","msg":"trace[1155939421] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5534; }","duration":"814.288343ms","start":"2026-07-01T10:44:37.886107Z","end":"2026-07-01T10:44:38.700395Z","steps":["trace[1155939421] 'agreement among raft nodes before linearized reading' (duration: 814.13602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.700468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:37.886093Z","time spent":"814.359505ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-07-01T10:44:38.700313Z","caller":"traceutil/trace.go:171","msg":"trace[136954611] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"678.680889ms","start":"2026-07-01T10:44:38.021542Z","end":"2026-07-01T10:44:38.700223Z","steps":["trace[136954611] 'process raft request' (duration: 406.340991ms)","trace[136954611] 'compare' (duration: 271.987942ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:44:38.70065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.246244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T10:44:38.700707Z","caller":"traceutil/trace.go:171","msg":"trace[1663759783] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5534; }","duration":"842.349026ms","start":"2026-07-01T10:44:37.858341Z","end":"2026-07-01T10:44:38.70069Z","steps":["trace[1663759783] 'agreement among raft nodes before linearized reading' (duration: 842.167212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.700766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:37.858318Z","time spent":"842.434638ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T10:44:38.700783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:38.021514Z","time spent":"679.163859ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:44:38.700859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.10442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-07-01T10:44:38.700898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.712406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:44:38.700932Z","caller":"traceutil/trace.go:171","msg":"trace[1522155665] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5534; }","duration":"188.179471ms","start":"2026-07-01T10:44:38.512734Z","end":"2026-07-01T10:44:38.700913Z","steps":["trace[1522155665] 'agreement among raft nodes before linearized reading' (duration: 187.959167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.70092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.534903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17232"} {"level":"info","ts":"2026-07-01T10:44:38.700986Z","caller":"traceutil/trace.go:171","msg":"trace[403861756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5534; }","duration":"262.832748ms","start":"2026-07-01T10:44:38.43813Z","end":"2026-07-01T10:44:38.700963Z","steps":["trace[403861756] 'agreement among raft nodes before linearized reading' (duration: 262.601174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.700973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.354445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80284"} {"level":"info","ts":"2026-07-01T10:44:38.701015Z","caller":"traceutil/trace.go:171","msg":"trace[899858355] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5534; }","duration":"232.645125ms","start":"2026-07-01T10:44:38.468347Z","end":"2026-07-01T10:44:38.700992Z","steps":["trace[899858355] 'agreement among raft nodes before linearized reading' (duration: 232.35425ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:44:38.701063Z","caller":"traceutil/trace.go:171","msg":"trace[1319281799] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5534; }","duration":"384.446596ms","start":"2026-07-01T10:44:38.316602Z","end":"2026-07-01T10:44:38.701048Z","steps":["trace[1319281799] 'agreement among raft nodes before linearized reading' (duration: 384.07809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:44:38.701115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:44:38.316526Z","time spent":"384.576198ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80306,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-01T10:44:48.675285Z","caller":"traceutil/trace.go:171","msg":"trace[1459512539] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"105.229386ms","start":"2026-07-01T10:44:48.570026Z","end":"2026-07-01T10:44:48.675256Z","steps":["trace[1459512539] 'process raft request' (duration: 105.089464ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:45:40.698344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-07-01T10:45:40.783873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"83.955771ms","hash":742295442,"current-db-size-bytes":57298944,"current-db-size":"57 MB","current-db-size-in-use-bytes":33665024,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T10:45:40.783935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":742295442,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-07-01T10:50:40.704422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6070} {"level":"info","ts":"2026-07-01T10:50:40.807589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6070,"took":"101.163682ms","hash":2306986670,"current-db-size-bytes":57298944,"current-db-size":"57 MB","current-db-size-in-use-bytes":23887872,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-01T10:50:40.807664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2306986670,"revision":6070,"compact-revision":2998} {"level":"warn","ts":"2026-07-01T10:50:41.258978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.913885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T10:50:41.259052Z","caller":"traceutil/trace.go:171","msg":"trace[2092851211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8301; }","duration":"222.053118ms","start":"2026-07-01T10:50:41.036981Z","end":"2026-07-01T10:50:41.259034Z","steps":["trace[2092851211] 'range keys from in-memory index tree' (duration: 221.724472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:41.259199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.598956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:50:41.259219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.119593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:50:41.259278Z","caller":"traceutil/trace.go:171","msg":"trace[1338349422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8301; }","duration":"224.75736ms","start":"2026-07-01T10:50:41.034501Z","end":"2026-07-01T10:50:41.259258Z","steps":["trace[1338349422] 'range keys from in-memory index tree' (duration: 224.488005ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:41.259325Z","caller":"traceutil/trace.go:171","msg":"trace[473219695] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8301; }","duration":"199.279577ms","start":"2026-07-01T10:50:41.060021Z","end":"2026-07-01T10:50:41.259301Z","steps":["trace[473219695] 'count revisions from in-memory index tree' (duration: 199.011431ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.261657Z","caller":"traceutil/trace.go:171","msg":"trace[294389492] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"100.847926ms","start":"2026-07-01T10:50:52.160746Z","end":"2026-07-01T10:50:52.261594Z","steps":["trace[294389492] 'process raft request' (duration: 100.688033ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.306871Z","caller":"traceutil/trace.go:171","msg":"trace[1635092130] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"143.801534ms","start":"2026-07-01T10:50:52.163025Z","end":"2026-07-01T10:50:52.306826Z","steps":["trace[1635092130] 'process raft request' (duration: 143.344326ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.307017Z","caller":"traceutil/trace.go:171","msg":"trace[1347409544] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"140.305207ms","start":"2026-07-01T10:50:52.166666Z","end":"2026-07-01T10:50:52.306971Z","steps":["trace[1347409544] 'process raft request' (duration: 139.823508ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.793753Z","caller":"traceutil/trace.go:171","msg":"trace[1706307605] linearizableReadLoop","detail":"{readStateIndex:8658; appliedIndex:8657; }","duration":"148.198496ms","start":"2026-07-01T10:50:52.645531Z","end":"2026-07-01T10:50:52.79373Z","steps":["trace[1706307605] 'read index received' (duration: 148.013502ms)","trace[1706307605] 'applied index is now lower than readState.Index' (duration: 180.584µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:50:52.793854Z","caller":"traceutil/trace.go:171","msg":"trace[55950605] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"148.584672ms","start":"2026-07-01T10:50:52.64522Z","end":"2026-07-01T10:50:52.793804Z","steps":["trace[55950605] 'process raft request' (duration: 148.347778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:52.794607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.341566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:50:52.793961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.416689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:50:52.794645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.118027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T10:50:52.794651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.290659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:50:52.794688Z","caller":"traceutil/trace.go:171","msg":"trace[373259852] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8364; }","duration":"140.488679ms","start":"2026-07-01T10:50:52.654181Z","end":"2026-07-01T10:50:52.79467Z","steps":["trace[373259852] 'agreement among raft nodes before linearized reading' (duration: 140.342656ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.794707Z","caller":"traceutil/trace.go:171","msg":"trace[1422025011] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8364; }","duration":"149.218594ms","start":"2026-07-01T10:50:52.645465Z","end":"2026-07-01T10:50:52.794684Z","steps":["trace[1422025011] 'agreement among raft nodes before linearized reading' (duration: 148.42207ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.79473Z","caller":"traceutil/trace.go:171","msg":"trace[934833349] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8364; }","duration":"135.428502ms","start":"2026-07-01T10:50:52.659285Z","end":"2026-07-01T10:50:52.794713Z","steps":["trace[934833349] 'agreement among raft nodes before linearized reading' (duration: 135.299309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:52.794798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.484009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:50:52.794856Z","caller":"traceutil/trace.go:171","msg":"trace[394181159] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8364; }","duration":"129.5624ms","start":"2026-07-01T10:50:52.66528Z","end":"2026-07-01T10:50:52.794843Z","steps":["trace[394181159] 'agreement among raft nodes before linearized reading' (duration: 129.467218ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.794717Z","caller":"traceutil/trace.go:171","msg":"trace[1557719516] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8364; }","duration":"135.239598ms","start":"2026-07-01T10:50:52.659459Z","end":"2026-07-01T10:50:52.794699Z","steps":["trace[1557719516] 'agreement among raft nodes before linearized reading' (duration: 135.092456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:52.79494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.645006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:50:52.795016Z","caller":"traceutil/trace.go:171","msg":"trace[128713861] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8364; }","duration":"135.774929ms","start":"2026-07-01T10:50:52.659218Z","end":"2026-07-01T10:50:52.794993Z","steps":["trace[128713861] 'agreement among raft nodes before linearized reading' (duration: 135.648215ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:52.901353Z","caller":"traceutil/trace.go:171","msg":"trace[832243389] linearizableReadLoop","detail":"{readStateIndex:8660; appliedIndex:8658; }","duration":"100.247744ms","start":"2026-07-01T10:50:52.801076Z","end":"2026-07-01T10:50:52.901324Z","steps":["trace[832243389] 'read index received' (duration: 36.068016ms)","trace[832243389] 'applied index is now lower than readState.Index' (duration: 64.178388ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:50:52.901413Z","caller":"traceutil/trace.go:171","msg":"trace[500904437] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"101.980078ms","start":"2026-07-01T10:50:52.799395Z","end":"2026-07-01T10:50:52.901375Z","steps":["trace[500904437] 'process raft request' (duration: 59.420429ms)","trace[500904437] 'compare' (duration: 42.355135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:50:52.901602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.49794ms","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-07-01T10:50:52.901693Z","caller":"traceutil/trace.go:171","msg":"trace[705695628] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8366; }","duration":"100.654253ms","start":"2026-07-01T10:50:52.801019Z","end":"2026-07-01T10:50:52.901674Z","steps":["trace[705695628] 'agreement among raft nodes before linearized reading' (duration: 100.424629ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:53.674599Z","caller":"traceutil/trace.go:171","msg":"trace[785209952] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"315.597297ms","start":"2026-07-01T10:50:53.358948Z","end":"2026-07-01T10:50:53.674545Z","steps":["trace[785209952] 'process raft request' (duration: 315.268701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:53.674872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:50:53.358924Z","time spent":"315.79594ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:50:54.94464Z","caller":"traceutil/trace.go:171","msg":"trace[58484987] linearizableReadLoop","detail":"{readStateIndex:8669; appliedIndex:8668; }","duration":"144.658589ms","start":"2026-07-01T10:50:54.799946Z","end":"2026-07-01T10:50:54.944604Z","steps":["trace[58484987] 'read index received' (duration: 144.362783ms)","trace[58484987] 'applied index is now lower than readState.Index' (duration: 293.646µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:50:54.944915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.954834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:50:54.944996Z","caller":"traceutil/trace.go:171","msg":"trace[1541718601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8374; }","duration":"145.096538ms","start":"2026-07-01T10:50:54.799883Z","end":"2026-07-01T10:50:54.944979Z","steps":["trace[1541718601] 'agreement among raft nodes before linearized reading' (duration: 144.834333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:50:54.945107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.312153ms","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-07-01T10:50:54.945223Z","caller":"traceutil/trace.go:171","msg":"trace[1493606537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8374; }","duration":"103.496707ms","start":"2026-07-01T10:50:54.841705Z","end":"2026-07-01T10:50:54.945202Z","steps":["trace[1493606537] 'agreement among raft nodes before linearized reading' (duration: 103.160191ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:50:54.945354Z","caller":"traceutil/trace.go:171","msg":"trace[1022519969] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"184.910003ms","start":"2026-07-01T10:50:54.759725Z","end":"2026-07-01T10:50:54.944635Z","steps":["trace[1022519969] 'process raft request' (duration: 184.649689ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.285585Z","caller":"traceutil/trace.go:171","msg":"trace[1292674426] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"186.403366ms","start":"2026-07-01T10:51:23.099153Z","end":"2026-07-01T10:51:23.285557Z","steps":["trace[1292674426] 'process raft request' (duration: 186.217513ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.286243Z","caller":"traceutil/trace.go:171","msg":"trace[1285325274] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8882; }","duration":"181.755489ms","start":"2026-07-01T10:51:23.104454Z","end":"2026-07-01T10:51:23.286209Z","steps":["trace[1285325274] 'read index received' (duration: 181.010185ms)","trace[1285325274] 'applied index is now lower than readState.Index' (duration: 744.424µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:23.286286Z","caller":"traceutil/trace.go:171","msg":"trace[640749057] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"186.59266ms","start":"2026-07-01T10:51:23.099681Z","end":"2026-07-01T10:51:23.286274Z","steps":["trace[640749057] 'process raft request' (duration: 186.407737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:23.286448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.981333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T10:51:23.286525Z","caller":"traceutil/trace.go:171","msg":"trace[285527934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8582; }","duration":"182.102665ms","start":"2026-07-01T10:51:23.104409Z","end":"2026-07-01T10:51:23.286512Z","steps":["trace[285527934] 'agreement among raft nodes before linearized reading' (duration: 181.943982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:23.286573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.907104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9pbzq\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-07-01T10:51:23.286579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.933052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-01T10:51:23.286601Z","caller":"traceutil/trace.go:171","msg":"trace[1648451537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9pbzq; range_end:; response_count:1; response_revision:8582; }","duration":"178.965286ms","start":"2026-07-01T10:51:23.107628Z","end":"2026-07-01T10:51:23.286593Z","steps":["trace[1648451537] 'agreement among raft nodes before linearized reading' (duration: 178.847704ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.286631Z","caller":"traceutil/trace.go:171","msg":"trace[1559378369] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8582; }","duration":"182.016694ms","start":"2026-07-01T10:51:23.104599Z","end":"2026-07-01T10:51:23.286616Z","steps":["trace[1559378369] 'agreement among raft nodes before linearized reading' (duration: 181.783839ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.96351Z","caller":"traceutil/trace.go:171","msg":"trace[942097727] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"132.566556ms","start":"2026-07-01T10:51:23.830915Z","end":"2026-07-01T10:51:23.963481Z","steps":["trace[942097727] 'process raft request' (duration: 95.189796ms)","trace[942097727] 'compare' (duration: 37.207667ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:23.99668Z","caller":"traceutil/trace.go:171","msg":"trace[375988954] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"163.285568ms","start":"2026-07-01T10:51:23.833376Z","end":"2026-07-01T10:51:23.996662Z","steps":["trace[375988954] 'process raft request' (duration: 163.103544ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.996725Z","caller":"traceutil/trace.go:171","msg":"trace[1348754576] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"163.291937ms","start":"2026-07-01T10:51:23.833403Z","end":"2026-07-01T10:51:23.996695Z","steps":["trace[1348754576] 'process raft request' (duration: 163.209416ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:24.608284Z","caller":"traceutil/trace.go:171","msg":"trace[157724282] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"149.881013ms","start":"2026-07-01T10:51:24.458379Z","end":"2026-07-01T10:51:24.60826Z","steps":["trace[157724282] 'process raft request' (duration: 149.785632ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:24.608393Z","caller":"traceutil/trace.go:171","msg":"trace[1624086872] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"162.919351ms","start":"2026-07-01T10:51:24.445418Z","end":"2026-07-01T10:51:24.608338Z","steps":["trace[1624086872] 'process raft request' (duration: 162.595245ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:35.954101Z","caller":"traceutil/trace.go:171","msg":"trace[14435323] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"156.53258ms","start":"2026-07-01T10:51:35.79754Z","end":"2026-07-01T10:51:35.954072Z","steps":["trace[14435323] 'process raft request' (duration: 152.558715ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:36.895314Z","caller":"traceutil/trace.go:171","msg":"trace[666484092] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9007; }","duration":"175.99096ms","start":"2026-07-01T10:51:36.719295Z","end":"2026-07-01T10:51:36.895285Z","steps":["trace[666484092] 'read index received' (duration: 117.479759ms)","trace[666484092] 'applied index is now lower than readState.Index' (duration: 58.50988ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:36.89556Z","caller":"traceutil/trace.go:171","msg":"trace[1062542602] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"249.083506ms","start":"2026-07-01T10:51:36.646453Z","end":"2026-07-01T10:51:36.895536Z","steps":["trace[1062542602] 'process raft request' (duration: 190.394843ms)","trace[1062542602] 'compare' (duration: 58.05361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:51:36.896647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.328475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-07-01T10:51:36.896708Z","caller":"traceutil/trace.go:171","msg":"trace[1135811205] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8701; }","duration":"177.403646ms","start":"2026-07-01T10:51:36.719288Z","end":"2026-07-01T10:51:36.896692Z","steps":["trace[1135811205] 'agreement among raft nodes before linearized reading' (duration: 176.096751ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:55.454668Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241f.wal"} {"level":"info","ts":"2026-07-01T10:52:35.610546Z","caller":"traceutil/trace.go:171","msg":"trace[1505249279] transaction","detail":"{read_only:false; response_revision:9333; number_of_response:1; }","duration":"121.601188ms","start":"2026-07-01T10:52:35.488917Z","end":"2026-07-01T10:52:35.610519Z","steps":["trace[1505249279] 'process raft request' (duration: 67.475271ms)","trace[1505249279] 'compare' (duration: 54.011915ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:53:20.580469Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T10:53:20.58557Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T10:53:20.585668Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T10:55:40.711761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8298} {"level":"info","ts":"2026-07-01T10:55:40.826558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8298,"took":"110.670154ms","hash":4127121777,"current-db-size-bytes":57298944,"current-db-size":"57 MB","current-db-size-in-use-bytes":26185728,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-01T10:55:40.826647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4127121777,"revision":8298,"compact-revision":6070} {"level":"info","ts":"2026-07-01T11:00:40.719949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10793} {"level":"info","ts":"2026-07-01T11:00:40.797715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10793,"took":"75.335073ms","hash":3037386512,"current-db-size-bytes":57298944,"current-db-size":"57 MB","current-db-size-in-use-bytes":27246592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-01T11:00:40.797791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3037386512,"revision":10793,"compact-revision":8298} {"level":"info","ts":"2026-07-01T11:05:40.728504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12836} {"level":"info","ts":"2026-07-01T11:05:40.806064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12836,"took":"75.147426ms","hash":1228233962,"current-db-size-bytes":57298944,"current-db-size":"57 MB","current-db-size-in-use-bytes":22192128,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-01T11:05:40.806159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1228233962,"revision":12836,"compact-revision":10793}