{"level":"warn","ts":"2026-07-21T06:36:19.005987Z","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-21T06:36:19.006895Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.63:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.63:2380","--initial-cluster=instance=https://199.19.213.63:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.63:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.63: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-21T06:36:19.006988Z","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-21T06:36:19.007006Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-07-21T06:36:19.007029Z","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-21T06:36:19.008148Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"]} {"level":"info","ts":"2026-07-21T06:36:19.008331Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.63: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-21T06:36:19.012208Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.651143ms"} {"level":"info","ts":"2026-07-21T06:36:19.019301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d75c480e593e7aed","cluster-id":"24fce67309ccbdc"} {"level":"info","ts":"2026-07-21T06:36:19.019394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:36:19.019416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 0"} {"level":"info","ts":"2026-07-21T06:36:19.019426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d75c480e593e7aed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:36:19.019438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 1"} {"level":"info","ts":"2026-07-21T06:36:19.019477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"warn","ts":"2026-07-21T06:36:19.03027Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:36:19.031935Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:36:19.03372Z","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-21T06:36:19.036277Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d75c480e593e7aed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:36:19.040112Z","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-21T06:36:19.040227Z","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-21T06:36:19.040239Z","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-21T06:36:19.039597Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d75c480e593e7aed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:36:19.041556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"info","ts":"2026-07-21T06:36:19.041668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","added-peer-id":"d75c480e593e7aed","added-peer-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-07-21T06:36:19.045054Z","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-21T06:36:19.045201Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-07-21T06:36:19.045221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-07-21T06:36:19.045385Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d75c480e593e7aed","initial-advertise-peer-urls":["https://199.19.213.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:36:19.045422Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:36:19.419843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:36:19.419939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:36:19.41997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgPreVoteResp from d75c480e593e7aed at term 1"} {"level":"info","ts":"2026-07-21T06:36:19.419992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:36:19.420028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgVoteResp from d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-07-21T06:36:19.420048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became leader at term 2"} {"level":"info","ts":"2026-07-21T06:36:19.420068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d75c480e593e7aed elected leader d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-07-21T06:36:19.423975Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d75c480e593e7aed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.63:2379]}","request-path":"/0/members/d75c480e593e7aed/attributes","cluster-id":"24fce67309ccbdc","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:36:19.424016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:36:19.424445Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:36:19.42447Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:36:19.424504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:36:19.424133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:36:19.42727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.63:2379"} {"level":"info","ts":"2026-07-21T06:36:19.42797Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:36:19.42808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:36:19.428104Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:36:19.428266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:36:21.144351Z","caller":"traceutil/trace.go:171","msg":"trace[177737977] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"127.165384ms","start":"2026-07-21T06:36:21.017141Z","end":"2026-07-21T06:36:21.144306Z","steps":["trace[177737977] 'process raft request' (duration: 53.77332ms)","trace[177737977] 'compare' (duration: 73.176563ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:21.161189Z","caller":"traceutil/trace.go:171","msg":"trace[620316015] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"119.828953ms","start":"2026-07-21T06:36:21.041332Z","end":"2026-07-21T06:36:21.161161Z","steps":["trace[620316015] 'process raft request' (duration: 119.540999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:26.461617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.584567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:36:26.461792Z","caller":"traceutil/trace.go:171","msg":"trace[1933813979] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"207.848451ms","start":"2026-07-21T06:36:26.253911Z","end":"2026-07-21T06:36:26.46176Z","steps":["trace[1933813979] 'process raft request' (duration: 42.491868ms)","trace[1933813979] 'compare' (duration: 164.372937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:26.807423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.305561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:36:26.807518Z","caller":"traceutil/trace.go:171","msg":"trace[1607392107] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"332.913566ms","start":"2026-07-21T06:36:26.47459Z","end":"2026-07-21T06:36:26.807504Z","steps":["trace[1607392107] 'process raft request' (duration: 148.457719ms)","trace[1607392107] 'compare' (duration: 184.171594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:26.807553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:26.474566Z","time spent":"332.972469ms","remote":"127.0.0.1:45590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:36:32.740591Z","caller":"traceutil/trace.go:171","msg":"trace[1572597802] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"124.692219ms","start":"2026-07-21T06:36:32.615866Z","end":"2026-07-21T06:36:32.740558Z","steps":["trace[1572597802] 'process raft request' (duration: 124.401556ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:59.447358Z","caller":"traceutil/trace.go:171","msg":"trace[1376290189] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"132.525546ms","start":"2026-07-21T06:36:59.314813Z","end":"2026-07-21T06:36:59.447338Z","steps":["trace[1376290189] 'process raft request' (duration: 132.209936ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:59.611121Z","caller":"traceutil/trace.go:171","msg":"trace[1899121332] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"160.991506ms","start":"2026-07-21T06:36:59.450103Z","end":"2026-07-21T06:36:59.611095Z","steps":["trace[1899121332] 'process raft request' (duration: 157.556913ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:37:03.580251Z","caller":"traceutil/trace.go:171","msg":"trace[1119339535] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"117.542733ms","start":"2026-07-21T06:37:03.462679Z","end":"2026-07-21T06:37:03.580222Z","steps":["trace[1119339535] 'read index received' (duration: 116.860482ms)","trace[1119339535] 'applied index is now lower than readState.Index' (duration: 681.291µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:03.58031Z","caller":"traceutil/trace.go:171","msg":"trace[1711607500] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"159.878133ms","start":"2026-07-21T06:37:03.420414Z","end":"2026-07-21T06:37:03.580292Z","steps":["trace[1711607500] 'process raft request' (duration: 159.302435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:03.580463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.736279ms","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-07-21T06:37:03.58057Z","caller":"traceutil/trace.go:171","msg":"trace[1518739677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"117.856623ms","start":"2026-07-21T06:37:03.462637Z","end":"2026-07-21T06:37:03.580493Z","steps":["trace[1518739677] 'agreement among raft nodes before linearized reading' (duration: 117.691728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:04.083403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.205616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:37:04.083499Z","caller":"traceutil/trace.go:171","msg":"trace[1247768630] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"446.68977ms","start":"2026-07-21T06:37:03.636797Z","end":"2026-07-21T06:37:04.083487Z","steps":["trace[1247768630] 'read index received' (duration: 261.31219ms)","trace[1247768630] 'applied index is now lower than readState.Index' (duration: 185.37674ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:04.083542Z","caller":"traceutil/trace.go:171","msg":"trace[334933125] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"452.321957ms","start":"2026-07-21T06:37:03.631184Z","end":"2026-07-21T06:37:04.083505Z","steps":["trace[334933125] 'process raft request' (duration: 266.826533ms)","trace[334933125] 'compare' (duration: 185.078101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:37:04.083657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.854975ms","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-07-21T06:37:04.083675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:03.631173Z","time spent":"452.43226ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:37:04.083698Z","caller":"traceutil/trace.go:171","msg":"trace[1464383346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"446.909746ms","start":"2026-07-21T06:37:03.63678Z","end":"2026-07-21T06:37:04.083689Z","steps":["trace[1464383346] 'agreement among raft nodes before linearized reading' (duration: 446.788343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:04.083724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:03.636774Z","time spent":"446.943757ms","remote":"127.0.0.1:45666","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-07-21T06:37:04.106238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.882461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8b5gx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T06:37:04.106299Z","caller":"traceutil/trace.go:171","msg":"trace[1661798863] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8b5gx; range_end:; response_count:1; response_revision:636; }","duration":"426.986205ms","start":"2026-07-21T06:37:03.6793Z","end":"2026-07-21T06:37:04.106287Z","steps":["trace[1661798863] 'agreement among raft nodes before linearized reading' (duration: 426.798099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:04.106331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:03.679282Z","time spent":"427.042946ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-8b5gx\" "} {"level":"info","ts":"2026-07-21T06:37:04.106705Z","caller":"traceutil/trace.go:171","msg":"trace[1308387801] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"458.753223ms","start":"2026-07-21T06:37:03.64731Z","end":"2026-07-21T06:37:04.106064Z","steps":["trace[1308387801] 'process raft request' (duration: 458.628909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:04.106779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:03.647288Z","time spent":"459.448385ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:37:04.106325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.076578ms","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-21T06:37:04.107177Z","caller":"traceutil/trace.go:171","msg":"trace[1259522710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"463.935604ms","start":"2026-07-21T06:37:03.643212Z","end":"2026-07-21T06:37:04.107148Z","steps":["trace[1259522710] 'agreement among raft nodes before linearized reading' (duration: 462.8341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:04.107482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:03.643206Z","time spent":"464.239165ms","remote":"127.0.0.1:45666","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-07-21T06:37:19.681604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.289251ms","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-21T06:37:19.681687Z","caller":"traceutil/trace.go:171","msg":"trace[40837310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"200.415414ms","start":"2026-07-21T06:37:19.481255Z","end":"2026-07-21T06:37:19.68167Z","steps":["trace[40837310] 'range keys from in-memory index tree' (duration: 200.155187ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:42.247031Z","caller":"traceutil/trace.go:171","msg":"trace[849256249] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"101.72566ms","start":"2026-07-21T06:38:42.145279Z","end":"2026-07-21T06:38:42.247005Z","steps":["trace[849256249] 'read index received' (duration: 101.590758ms)","trace[849256249] 'applied index is now lower than readState.Index' (duration: 133.692µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:38:42.247214Z","caller":"traceutil/trace.go:171","msg":"trace[1896021016] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"111.106085ms","start":"2026-07-21T06:38:42.135988Z","end":"2026-07-21T06:38:42.247094Z","steps":["trace[1896021016] 'process raft request' (duration: 110.82596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:42.247298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.002205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2cb14e4-1a5f-4b4d-b9a8-41508a425157\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-21T06:38:42.247486Z","caller":"traceutil/trace.go:171","msg":"trace[1798001183] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2cb14e4-1a5f-4b4d-b9a8-41508a425157; range_end:; response_count:1; response_revision:1881; }","duration":"102.233748ms","start":"2026-07-21T06:38:42.145238Z","end":"2026-07-21T06:38:42.247472Z","steps":["trace[1798001183] 'agreement among raft nodes before linearized reading' (duration: 101.918043ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:42.425577Z","caller":"traceutil/trace.go:171","msg":"trace[383448416] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"171.802547ms","start":"2026-07-21T06:38:42.253723Z","end":"2026-07-21T06:38:42.425525Z","steps":["trace[383448416] 'process raft request' (duration: 135.846403ms)","trace[383448416] 'compare' (duration: 35.70116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:38:42.425712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.36338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c43ab95d41db22\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-21T06:38:42.425759Z","caller":"traceutil/trace.go:171","msg":"trace[1253503675] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c43ab95d41db22; range_end:; response_count:1; response_revision:1883; }","duration":"134.458551ms","start":"2026-07-21T06:38:42.291288Z","end":"2026-07-21T06:38:42.425747Z","steps":["trace[1253503675] 'agreement among raft nodes before linearized reading' (duration: 134.290658ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:42.425584Z","caller":"traceutil/trace.go:171","msg":"trace[1676297957] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1957; }","duration":"134.060604ms","start":"2026-07-21T06:38:42.291336Z","end":"2026-07-21T06:38:42.425397Z","steps":["trace[1676297957] 'read index received' (duration: 21.376723ms)","trace[1676297957] 'applied index is now lower than readState.Index' (duration: 112.682741ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:38:42.725696Z","caller":"traceutil/trace.go:171","msg":"trace[1571971916] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"256.07902ms","start":"2026-07-21T06:38:42.469589Z","end":"2026-07-21T06:38:42.725668Z","steps":["trace[1571971916] 'process raft request' (duration: 246.288009ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:42.728732Z","caller":"traceutil/trace.go:171","msg":"trace[452278715] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"192.018191ms","start":"2026-07-21T06:38:42.536701Z","end":"2026-07-21T06:38:42.728719Z","steps":["trace[452278715] 'read index received' (duration: 179.219211ms)","trace[452278715] 'applied index is now lower than readState.Index' (duration: 12.79679ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:38:42.728897Z","caller":"traceutil/trace.go:171","msg":"trace[878896112] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"215.175255ms","start":"2026-07-21T06:38:42.513688Z","end":"2026-07-21T06:38:42.728863Z","steps":["trace[878896112] 'process raft request' (duration: 214.945551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:42.729013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.294806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:38:42.729169Z","caller":"traceutil/trace.go:171","msg":"trace[2105715111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"192.439698ms","start":"2026-07-21T06:38:42.536669Z","end":"2026-07-21T06:38:42.729109Z","steps":["trace[2105715111] 'agreement among raft nodes before linearized reading' (duration: 192.180313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:42.728998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.47155ms","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-07-21T06:38:42.729301Z","caller":"traceutil/trace.go:171","msg":"trace[1447123197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"140.816685ms","start":"2026-07-21T06:38:42.588472Z","end":"2026-07-21T06:38:42.729288Z","steps":["trace[1447123197] 'agreement among raft nodes before linearized reading' (duration: 140.430709ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:48.412171Z","caller":"traceutil/trace.go:171","msg":"trace[1460420152] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2025; }","duration":"143.957045ms","start":"2026-07-21T06:38:48.268197Z","end":"2026-07-21T06:38:48.412154Z","steps":["trace[1460420152] 'read index received' (duration: 36.910346ms)","trace[1460420152] 'applied index is now lower than readState.Index' (duration: 107.045959ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:38:48.412189Z","caller":"traceutil/trace.go:171","msg":"trace[74597887] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"181.590414ms","start":"2026-07-21T06:38:48.230566Z","end":"2026-07-21T06:38:48.412156Z","steps":["trace[74597887] 'process raft request' (duration: 164.087752ms)","trace[74597887] 'compare' (duration: 17.212688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:38:48.412414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.75583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-07-21T06:38:48.412408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.20246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-21T06:38:48.412443Z","caller":"traceutil/trace.go:171","msg":"trace[493770347] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1951; }","duration":"141.791471ms","start":"2026-07-21T06:38:48.27064Z","end":"2026-07-21T06:38:48.412431Z","steps":["trace[493770347] 'agreement among raft nodes before linearized reading' (duration: 141.687499ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:48.412462Z","caller":"traceutil/trace.go:171","msg":"trace[712064876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1951; }","duration":"144.285361ms","start":"2026-07-21T06:38:48.268164Z","end":"2026-07-21T06:38:48.41245Z","steps":["trace[712064876] 'agreement among raft nodes before linearized reading' (duration: 144.137699ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:48.41258Z","caller":"traceutil/trace.go:171","msg":"trace[187646062] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"181.93092ms","start":"2026-07-21T06:38:48.230637Z","end":"2026-07-21T06:38:48.412568Z","steps":["trace[187646062] 'process raft request' (duration: 181.420332ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:48.412791Z","caller":"traceutil/trace.go:171","msg":"trace[415031099] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"182.088102ms","start":"2026-07-21T06:38:48.230694Z","end":"2026-07-21T06:38:48.412782Z","steps":["trace[415031099] 'process raft request' (duration: 181.427692ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:16.730126Z","caller":"traceutil/trace.go:171","msg":"trace[246027206] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"149.121349ms","start":"2026-07-21T06:39:16.580987Z","end":"2026-07-21T06:39:16.730109Z","steps":["trace[246027206] 'read index received' (duration: 148.991767ms)","trace[246027206] 'applied index is now lower than readState.Index' (duration: 128.972µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:16.730173Z","caller":"traceutil/trace.go:171","msg":"trace[980172984] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"150.328547ms","start":"2026-07-21T06:39:16.579814Z","end":"2026-07-21T06:39:16.730143Z","steps":["trace[980172984] 'process raft request' (duration: 150.188995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:16.730326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.334902ms","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-07-21T06:39:16.730362Z","caller":"traceutil/trace.go:171","msg":"trace[162451028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2123; }","duration":"149.407313ms","start":"2026-07-21T06:39:16.58094Z","end":"2026-07-21T06:39:16.730348Z","steps":["trace[162451028] 'agreement among raft nodes before linearized reading' (duration: 149.301031ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:16.988178Z","caller":"traceutil/trace.go:171","msg":"trace[1268473454] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"254.657018ms","start":"2026-07-21T06:39:16.733498Z","end":"2026-07-21T06:39:16.988155Z","steps":["trace[1268473454] 'process raft request' (duration: 212.143786ms)","trace[1268473454] 'compare' (duration: 42.412371ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:17.006254Z","caller":"traceutil/trace.go:171","msg":"trace[380981499] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"107.433357ms","start":"2026-07-21T06:39:16.89878Z","end":"2026-07-21T06:39:17.006213Z","steps":["trace[380981499] 'process raft request' (duration: 107.012001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:17.234337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.317287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:39:17.234762Z","caller":"traceutil/trace.go:171","msg":"trace[991842925] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"223.012149ms","start":"2026-07-21T06:39:17.011737Z","end":"2026-07-21T06:39:17.234749Z","steps":["trace[991842925] 'process raft request' (duration: 222.722145ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:17.234987Z","caller":"traceutil/trace.go:171","msg":"trace[1452754724] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"200.09875ms","start":"2026-07-21T06:39:17.034879Z","end":"2026-07-21T06:39:17.234978Z","steps":["trace[1452754724] 'process raft request' (duration: 199.611382ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:17.234531Z","caller":"traceutil/trace.go:171","msg":"trace[61056115] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2208; }","duration":"148.927189ms","start":"2026-07-21T06:39:17.085591Z","end":"2026-07-21T06:39:17.234518Z","steps":["trace[61056115] 'read index received' (duration: 36.140756ms)","trace[61056115] 'applied index is now lower than readState.Index' (duration: 112.785733ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:17.235198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.61178ms","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-21T06:39:17.235225Z","caller":"traceutil/trace.go:171","msg":"trace[37788195] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2129; }","duration":"149.67362ms","start":"2026-07-21T06:39:17.085543Z","end":"2026-07-21T06:39:17.235216Z","steps":["trace[37788195] 'agreement among raft nodes before linearized reading' (duration: 149.535088ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:17.235437Z","caller":"traceutil/trace.go:171","msg":"trace[373874880] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"224.583782ms","start":"2026-07-21T06:39:17.00988Z","end":"2026-07-21T06:39:17.234464Z","steps":["trace[373874880] 'process raft request' (duration: 111.836909ms)","trace[373874880] 'compare' (duration: 112.184735ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:17.234563Z","caller":"traceutil/trace.go:171","msg":"trace[976641239] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"223.708919ms","start":"2026-07-21T06:39:17.010836Z","end":"2026-07-21T06:39:17.234544Z","steps":["trace[976641239] 'process raft request' (duration: 223.580647ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:21.392675Z","caller":"traceutil/trace.go:171","msg":"trace[1612699662] linearizableReadLoop","detail":"{readStateIndex:2672; appliedIndex:2671; }","duration":"123.598955ms","start":"2026-07-21T06:40:21.269058Z","end":"2026-07-21T06:40:21.392657Z","steps":["trace[1612699662] 'read index received' (duration: 123.432012ms)","trace[1612699662] 'applied index is now lower than readState.Index' (duration: 166.373µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:21.392765Z","caller":"traceutil/trace.go:171","msg":"trace[1092307566] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"152.212657ms","start":"2026-07-21T06:40:21.240543Z","end":"2026-07-21T06:40:21.392756Z","steps":["trace[1092307566] 'process raft request' (duration: 152.001714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:21.39289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.829547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:40:21.392917Z","caller":"traceutil/trace.go:171","msg":"trace[1986293254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2574; }","duration":"123.915168ms","start":"2026-07-21T06:40:21.268996Z","end":"2026-07-21T06:40:21.392911Z","steps":["trace[1986293254] 'agreement among raft nodes before linearized reading' (duration: 123.852367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:21.39315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.673869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-21T06:40:21.393176Z","caller":"traceutil/trace.go:171","msg":"trace[170921772] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2574; }","duration":"115.74597ms","start":"2026-07-21T06:40:21.277422Z","end":"2026-07-21T06:40:21.393168Z","steps":["trace[170921772] 'agreement among raft nodes before linearized reading' (duration: 115.662859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:21.393304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.391595ms","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-07-21T06:40:21.393327Z","caller":"traceutil/trace.go:171","msg":"trace[824728234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2574; }","duration":"112.446026ms","start":"2026-07-21T06:40:21.280876Z","end":"2026-07-21T06:40:21.393322Z","steps":["trace[824728234] 'agreement among raft nodes before linearized reading' (duration: 112.389855ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:54.155589Z","caller":"traceutil/trace.go:171","msg":"trace[1389841964] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"140.7224ms","start":"2026-07-21T06:40:54.014844Z","end":"2026-07-21T06:40:54.155566Z","steps":["trace[1389841964] 'process raft request' (duration: 120.665724ms)","trace[1389841964] 'compare' (duration: 19.551802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:46.816077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.457646ms","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-21T06:44:46.816177Z","caller":"traceutil/trace.go:171","msg":"trace[321500133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4739; }","duration":"122.587749ms","start":"2026-07-21T06:44:46.693568Z","end":"2026-07-21T06:44:46.816155Z","steps":["trace[321500133] 'range keys from in-memory index tree' (duration: 122.337583ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:46.990747Z","caller":"traceutil/trace.go:171","msg":"trace[1566480910] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"168.280911ms","start":"2026-07-21T06:44:46.822431Z","end":"2026-07-21T06:44:46.990712Z","steps":["trace[1566480910] 'process raft request' (duration: 168.013706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:00.628209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.032972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:45:00.628401Z","caller":"traceutil/trace.go:171","msg":"trace[333441409] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5103; }","duration":"173.429188ms","start":"2026-07-21T06:45:00.454873Z","end":"2026-07-21T06:45:00.628302Z","steps":["trace[333441409] 'read index received' (duration: 37.211µs)","trace[333441409] 'applied index is now lower than readState.Index' (duration: 173.387987ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:00.628485Z","caller":"traceutil/trace.go:171","msg":"trace[2056560462] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"234.756621ms","start":"2026-07-21T06:45:00.393716Z","end":"2026-07-21T06:45:00.628472Z","steps":["trace[2056560462] 'process raft request' (duration: 24.373222ms)","trace[2056560462] 'compare' (duration: 209.786647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:00.628726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.835936ms","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-21T06:45:00.628832Z","caller":"traceutil/trace.go:171","msg":"trace[1477871111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4917; }","duration":"173.98391ms","start":"2026-07-21T06:45:00.454826Z","end":"2026-07-21T06:45:00.62881Z","steps":["trace[1477871111] 'agreement among raft nodes before linearized reading' (duration: 173.743785ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:00.640715Z","caller":"traceutil/trace.go:171","msg":"trace[511606989] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"134.569818ms","start":"2026-07-21T06:45:00.506123Z","end":"2026-07-21T06:45:00.640693Z","steps":["trace[511606989] 'process raft request' (duration: 134.288172ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.048159Z","caller":"traceutil/trace.go:171","msg":"trace[1668447287] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"106.366208ms","start":"2026-07-21T06:45:00.941766Z","end":"2026-07-21T06:45:01.048133Z","steps":["trace[1668447287] 'read index received' (duration: 90.612034ms)","trace[1668447287] 'applied index is now lower than readState.Index' (duration: 15.753254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:01.048547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.31905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-07-21T06:45:01.04858Z","caller":"traceutil/trace.go:171","msg":"trace[2102300046] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4922; }","duration":"129.589446ms","start":"2026-07-21T06:45:00.91898Z","end":"2026-07-21T06:45:01.04857Z","steps":["trace[2102300046] 'agreement among raft nodes before linearized reading' (duration: 129.256349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:01.048606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.968137ms","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-21T06:45:01.04863Z","caller":"traceutil/trace.go:171","msg":"trace[1805207629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4922; }","duration":"121.027689ms","start":"2026-07-21T06:45:00.927596Z","end":"2026-07-21T06:45:01.048623Z","steps":["trace[1805207629] 'agreement among raft nodes before linearized reading' (duration: 120.967707ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.048754Z","caller":"traceutil/trace.go:171","msg":"trace[1646766825] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"125.959891ms","start":"2026-07-21T06:45:00.922788Z","end":"2026-07-21T06:45:01.048748Z","steps":["trace[1646766825] 'process raft request' (duration: 109.608155ms)","trace[1646766825] 'compare' (duration: 15.624832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:01.048835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.553365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-21T06:45:01.048868Z","caller":"traceutil/trace.go:171","msg":"trace[1718625177] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4922; }","duration":"128.604285ms","start":"2026-07-21T06:45:00.920252Z","end":"2026-07-21T06:45:01.048857Z","steps":["trace[1718625177] 'agreement among raft nodes before linearized reading' (duration: 128.517964ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.131921Z","caller":"traceutil/trace.go:171","msg":"trace[622890696] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"187.24809ms","start":"2026-07-21T06:45:00.944628Z","end":"2026-07-21T06:45:01.131876Z","steps":["trace[622890696] 'process raft request' (duration: 187.041366ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.132007Z","caller":"traceutil/trace.go:171","msg":"trace[1838947612] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"127.149494ms","start":"2026-07-21T06:45:01.004829Z","end":"2026-07-21T06:45:01.131978Z","steps":["trace[1838947612] 'process raft request' (duration: 126.979ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.132153Z","caller":"traceutil/trace.go:171","msg":"trace[2078303441] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"106.345065ms","start":"2026-07-21T06:45:01.025537Z","end":"2026-07-21T06:45:01.131883Z","steps":["trace[2078303441] 'process raft request' (duration: 106.307124ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.222683Z","caller":"traceutil/trace.go:171","msg":"trace[1795182239] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"166.423928ms","start":"2026-07-21T06:45:01.056236Z","end":"2026-07-21T06:45:01.22266Z","steps":["trace[1795182239] 'process raft request' (duration: 166.332857ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.222691Z","caller":"traceutil/trace.go:171","msg":"trace[600868169] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"181.638322ms","start":"2026-07-21T06:45:01.041011Z","end":"2026-07-21T06:45:01.222649Z","steps":["trace[600868169] 'process raft request' (duration: 146.823287ms)","trace[600868169] 'compare' (duration: 34.611621ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:01.222977Z","caller":"traceutil/trace.go:171","msg":"trace[1558964037] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"164.584112ms","start":"2026-07-21T06:45:01.05838Z","end":"2026-07-21T06:45:01.222964Z","steps":["trace[1558964037] 'process raft request' (duration: 164.242665ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.223154Z","caller":"traceutil/trace.go:171","msg":"trace[607684757] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"164.52605ms","start":"2026-07-21T06:45:01.058618Z","end":"2026-07-21T06:45:01.223144Z","steps":["trace[607684757] 'process raft request' (duration: 164.194464ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.22329Z","caller":"traceutil/trace.go:171","msg":"trace[1903796860] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5109; }","duration":"167.031911ms","start":"2026-07-21T06:45:01.056238Z","end":"2026-07-21T06:45:01.22327Z","steps":["trace[1903796860] 'read index received' (duration: 75.411469ms)","trace[1903796860] 'applied index is now lower than readState.Index' (duration: 91.619682ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:01.223404Z","caller":"traceutil/trace.go:171","msg":"trace[2028462164] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"164.513219ms","start":"2026-07-21T06:45:01.058881Z","end":"2026-07-21T06:45:01.223394Z","steps":["trace[2028462164] 'process raft request' (duration: 164.174072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:01.223549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.296257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-21T06:45:01.223582Z","caller":"traceutil/trace.go:171","msg":"trace[23686159] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4931; }","duration":"167.371218ms","start":"2026-07-21T06:45:01.056204Z","end":"2026-07-21T06:45:01.223575Z","steps":["trace[23686159] 'agreement among raft nodes before linearized reading' (duration: 167.193634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:01.223662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.382718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c783ce0e-2744-4280-90b0-28247e52012b\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-21T06:45:01.223724Z","caller":"traceutil/trace.go:171","msg":"trace[1423883234] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c783ce0e-2744-4280-90b0-28247e52012b; range_end:; response_count:1; response_revision:4931; }","duration":"165.487681ms","start":"2026-07-21T06:45:01.058218Z","end":"2026-07-21T06:45:01.223706Z","steps":["trace[1423883234] 'agreement among raft nodes before linearized reading' (duration: 165.327127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:01.224026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.705885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:45:01.224071Z","caller":"traceutil/trace.go:171","msg":"trace[1585110559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4931; }","duration":"165.766207ms","start":"2026-07-21T06:45:01.058295Z","end":"2026-07-21T06:45:01.224062Z","steps":["trace[1585110559] 'agreement among raft nodes before linearized reading' (duration: 165.660714ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:01.223691Z","caller":"traceutil/trace.go:171","msg":"trace[618870516] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"164.345246ms","start":"2026-07-21T06:45:01.059339Z","end":"2026-07-21T06:45:01.223684Z","steps":["trace[618870516] 'process raft request' (duration: 163.868886ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:33.073398Z","caller":"traceutil/trace.go:171","msg":"trace[1624134671] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"120.356237ms","start":"2026-07-21T06:45:32.953013Z","end":"2026-07-21T06:45:33.07337Z","steps":["trace[1624134671] 'process raft request' (duration: 97.885445ms)","trace[1624134671] 'compare' (duration: 22.3227ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:53.726837Z","caller":"traceutil/trace.go:171","msg":"trace[2047422049] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"177.412759ms","start":"2026-07-21T06:45:53.549392Z","end":"2026-07-21T06:45:53.726804Z","steps":["trace[2047422049] 'process raft request' (duration: 118.114279ms)","trace[2047422049] 'compare' (duration: 58.819161ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:53.726857Z","caller":"traceutil/trace.go:171","msg":"trace[547528276] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5572; }","duration":"142.205662ms","start":"2026-07-21T06:45:53.584629Z","end":"2026-07-21T06:45:53.726835Z","steps":["trace[547528276] 'read index received' (duration: 55.252521ms)","trace[547528276] 'applied index is now lower than readState.Index' (duration: 86.951551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:53.727118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.360135ms","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-07-21T06:45:53.727271Z","caller":"traceutil/trace.go:171","msg":"trace[2126418997] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5375; }","duration":"142.569189ms","start":"2026-07-21T06:45:53.58458Z","end":"2026-07-21T06:45:53.727149Z","steps":["trace[2126418997] 'agreement among raft nodes before linearized reading' (duration: 142.321294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:19.609054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2890} {"level":"info","ts":"2026-07-21T06:46:19.679156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2890,"took":"68.808389ms","hash":3817101342,"current-db-size-bytes":53338112,"current-db-size":"53 MB","current-db-size-in-use-bytes":29220864,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-21T06:46:19.679223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3817101342,"revision":2890,"compact-revision":-1} {"level":"info","ts":"2026-07-21T06:51:19.616007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5561} {"level":"info","ts":"2026-07-21T06:51:19.712133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5561,"took":"95.069121ms","hash":3609342505,"current-db-size-bytes":53338112,"current-db-size":"53 MB","current-db-size-in-use-bytes":18317312,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-21T06:51:19.71221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3609342505,"revision":5561,"compact-revision":2890}