{"level":"warn","ts":"2026-04-04T10:17:01.837498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:17:01.837583Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-04T10:17:01.837657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:17:01.837666Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-04T10:17:01.837685Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:17:01.838234Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-04T10:17:01.838303Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-04T10:17:01.844998Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.487292ms"} {"level":"info","ts":"2026-04-04T10:17:01.866481Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-04T10:17:01.866591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:17:01.866719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-04T10:17:01.866942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:17:01.866974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-04T10:17:01.867045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-04T10:17:01.880963Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:17:01.884685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:17:01.886506Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-04T10:17:01.890829Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:17:01.891092Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:17:01.891387Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:17:01.891445Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:17:01.891463Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:17:01.897361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-04T10:17:01.897472Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:17:01.897568Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-04T10:17:01.897676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-04T10:17:01.8978Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:17:01.897791Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-04T10:17:01.89785Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:17:02.268313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:17:02.268506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:17:02.268597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-04T10:17:02.268694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:17:02.268742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-04T10:17:02.268793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-04T10:17:02.268841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-04T10:17:02.269827Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:17:02.271392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:17:02.271414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:17:02.271461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:17:02.271757Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:17:02.271847Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:17:02.272209Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:17:02.273304Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:17:02.273428Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:17:02.274558Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:17:02.274966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-04T10:17:28.248931Z","caller":"traceutil/trace.go:171","msg":"trace[1218005657] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"152.40226ms","start":"2026-04-04T10:17:28.096503Z","end":"2026-04-04T10:17:28.248905Z","steps":["trace[1218005657] 'process raft request' (duration: 152.239985ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:33.020712Z","caller":"traceutil/trace.go:171","msg":"trace[1138249572] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"102.66621ms","start":"2026-04-04T10:17:32.918007Z","end":"2026-04-04T10:17:33.020673Z","steps":["trace[1138249572] 'process raft request' (duration: 102.509055ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:33.020913Z","caller":"traceutil/trace.go:171","msg":"trace[1634951953] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"102.770273ms","start":"2026-04-04T10:17:32.918116Z","end":"2026-04-04T10:17:33.020886Z","steps":["trace[1634951953] 'process raft request' (duration: 102.478965ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:38.858643Z","caller":"traceutil/trace.go:171","msg":"trace[1086922593] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"220.081264ms","start":"2026-04-04T10:17:38.638528Z","end":"2026-04-04T10:17:38.858609Z","steps":["trace[1086922593] 'read index received' (duration: 219.731424ms)","trace[1086922593] 'applied index is now lower than readState.Index' (duration: 347.639µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:17:38.859043Z","caller":"traceutil/trace.go:171","msg":"trace[137524822] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"242.366233ms","start":"2026-04-04T10:17:38.616603Z","end":"2026-04-04T10:17:38.85897Z","steps":["trace[137524822] 'process raft request' (duration: 241.126518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:38.859952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.425652ms","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-04-04T10:17:38.859947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.101593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:17:38.860044Z","caller":"traceutil/trace.go:171","msg":"trace[10049609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"221.514575ms","start":"2026-04-04T10:17:38.638515Z","end":"2026-04-04T10:17:38.86003Z","steps":["trace[10049609] 'agreement among raft nodes before linearized reading' (duration: 221.321379ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:38.860066Z","caller":"traceutil/trace.go:171","msg":"trace[157069053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"126.304239ms","start":"2026-04-04T10:17:38.733735Z","end":"2026-04-04T10:17:38.860039Z","steps":["trace[157069053] 'agreement among raft nodes before linearized reading' (duration: 126.121854ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:39.066247Z","caller":"traceutil/trace.go:171","msg":"trace[1642540247] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"149.197367ms","start":"2026-04-04T10:17:38.917027Z","end":"2026-04-04T10:17:39.066225Z","steps":["trace[1642540247] 'process raft request' (duration: 149.109844ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:39.06632Z","caller":"traceutil/trace.go:171","msg":"trace[465090136] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"201.350955ms","start":"2026-04-04T10:17:38.864925Z","end":"2026-04-04T10:17:39.066276Z","steps":["trace[465090136] 'process raft request' (duration: 137.624474ms)","trace[465090136] 'compare' (duration: 63.32273ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:17:39.312671Z","caller":"traceutil/trace.go:171","msg":"trace[1748146029] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"161.47547ms","start":"2026-04-04T10:17:39.151166Z","end":"2026-04-04T10:17:39.312642Z","steps":["trace[1748146029] 'process raft request' (duration: 134.197446ms)","trace[1748146029] 'compare' (duration: 27.016967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:17:39.747013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.611754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:17:39.747133Z","caller":"traceutil/trace.go:171","msg":"trace[1329695674] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"418.642949ms","start":"2026-04-04T10:17:39.32847Z","end":"2026-04-04T10:17:39.747113Z","steps":["trace[1329695674] 'process raft request' (duration: 193.341036ms)","trace[1329695674] 'compare' (duration: 224.467639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:17:39.747196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:17:39.328438Z","time spent":"418.727992ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:17:39.923954Z","caller":"traceutil/trace.go:171","msg":"trace[2049490804] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"427.686548ms","start":"2026-04-04T10:17:39.496241Z","end":"2026-04-04T10:17:39.923928Z","steps":["trace[2049490804] 'process raft request' (duration: 427.168004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:39.924133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:17:39.496212Z","time spent":"427.829863ms","remote":"127.0.0.1:43820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:18:39.755286Z","caller":"traceutil/trace.go:171","msg":"trace[1519294940] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"266.61772ms","start":"2026-04-04T10:18:39.488625Z","end":"2026-04-04T10:18:39.755243Z","steps":["trace[1519294940] 'process raft request' (duration: 219.647922ms)","trace[1519294940] 'compare' (duration: 46.762462ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:18:39.804273Z","caller":"traceutil/trace.go:171","msg":"trace[2106314798] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"225.960653ms","start":"2026-04-04T10:18:39.57827Z","end":"2026-04-04T10:18:39.80423Z","steps":["trace[2106314798] 'read index received' (duration: 130.057361ms)","trace[2106314798] 'applied index is now lower than readState.Index' (duration: 95.901702ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:18:39.804374Z","caller":"traceutil/trace.go:171","msg":"trace[1733504808] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"304.333972ms","start":"2026-04-04T10:18:39.499987Z","end":"2026-04-04T10:18:39.804321Z","steps":["trace[1733504808] 'process raft request' (duration: 304.087135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:39.804553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:18:39.49996Z","time spent":"304.502558ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:18:39.804631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.333135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-xkwbv\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-04T10:18:39.804727Z","caller":"traceutil/trace.go:171","msg":"trace[1523143734] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-xkwbv; range_end:; response_count:1; response_revision:1316; }","duration":"226.492429ms","start":"2026-04-04T10:18:39.578211Z","end":"2026-04-04T10:18:39.804704Z","steps":["trace[1523143734] 'agreement among raft nodes before linearized reading' (duration: 226.222011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:39.804959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.283959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:18:39.805033Z","caller":"traceutil/trace.go:171","msg":"trace[1935093681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"146.396542ms","start":"2026-04-04T10:18:39.65862Z","end":"2026-04-04T10:18:39.805016Z","steps":["trace[1935093681] 'agreement among raft nodes before linearized reading' (duration: 146.033792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:39.805003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.316211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-04T10:18:39.805159Z","caller":"traceutil/trace.go:171","msg":"trace[1409881689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"152.551568ms","start":"2026-04-04T10:18:39.652546Z","end":"2026-04-04T10:18:39.805097Z","steps":["trace[1409881689] 'agreement among raft nodes before linearized reading' (duration: 151.91696ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:18:47.016758Z","caller":"traceutil/trace.go:171","msg":"trace[220855428] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"155.335947ms","start":"2026-04-04T10:18:46.861362Z","end":"2026-04-04T10:18:47.016698Z","steps":["trace[220855428] 'read index received' (duration: 154.892705ms)","trace[220855428] 'applied index is now lower than readState.Index' (duration: 441.672µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:18:47.01687Z","caller":"traceutil/trace.go:171","msg":"trace[2014167232] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"213.750733ms","start":"2026-04-04T10:18:46.803062Z","end":"2026-04-04T10:18:47.016813Z","steps":["trace[2014167232] 'process raft request' (duration: 213.27274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:47.017127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.749118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-04T10:18:47.017413Z","caller":"traceutil/trace.go:171","msg":"trace[2047219656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1440; }","duration":"156.100999ms","start":"2026-04-04T10:18:46.861288Z","end":"2026-04-04T10:18:47.017389Z","steps":["trace[2047219656] 'agreement among raft nodes before linearized reading' (duration: 155.618125ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:10.298114Z","caller":"traceutil/trace.go:171","msg":"trace[664126479] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"380.150058ms","start":"2026-04-04T10:19:09.917614Z","end":"2026-04-04T10:19:10.297764Z","steps":["trace[664126479] 'process raft request' (duration: 339.752438ms)","trace[664126479] 'compare' (duration: 40.201965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:10.29851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:09.917532Z","time spent":"380.723284ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:19:10.317931Z","caller":"traceutil/trace.go:171","msg":"trace[1611967077] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"336.95249ms","start":"2026-04-04T10:19:09.980948Z","end":"2026-04-04T10:19:10.3179Z","steps":["trace[1611967077] 'process raft request' (duration: 336.758374ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:10.318116Z","caller":"traceutil/trace.go:171","msg":"trace[1356570060] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1896; }","duration":"314.553595ms","start":"2026-04-04T10:19:10.003537Z","end":"2026-04-04T10:19:10.318091Z","steps":["trace[1356570060] 'read index received' (duration: 253.820382ms)","trace[1356570060] 'applied index is now lower than readState.Index' (duration: 60.731333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:10.318135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:09.980932Z","time spent":"337.102254ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:19:10.318282Z","caller":"traceutil/trace.go:171","msg":"trace[1771294134] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"119.279833ms","start":"2026-04-04T10:19:10.198954Z","end":"2026-04-04T10:19:10.318234Z","steps":["trace[1771294134] 'process raft request' (duration: 118.894872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.318415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.920946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-04T10:19:10.318506Z","caller":"traceutil/trace.go:171","msg":"trace[2073719475] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1834; }","duration":"185.046679ms","start":"2026-04-04T10:19:10.133438Z","end":"2026-04-04T10:19:10.318485Z","steps":["trace[2073719475] 'agreement among raft nodes before linearized reading' (duration: 184.813293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.318428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.275807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:19:10.318613Z","caller":"traceutil/trace.go:171","msg":"trace[548987998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1834; }","duration":"314.506244ms","start":"2026-04-04T10:19:10.004085Z","end":"2026-04-04T10:19:10.318592Z","steps":["trace[548987998] 'agreement among raft nodes before linearized reading' (duration: 314.147154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.318698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:10.004066Z","time spent":"314.594846ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-04T10:19:10.319553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.973125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-04T10:19:10.319627Z","caller":"traceutil/trace.go:171","msg":"trace[1498724995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"316.105379ms","start":"2026-04-04T10:19:10.003503Z","end":"2026-04-04T10:19:10.319608Z","steps":["trace[1498724995] 'agreement among raft nodes before linearized reading' (duration: 314.71298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.319681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:10.00349Z","time spent":"316.175162ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-04T10:19:10.838103Z","caller":"traceutil/trace.go:171","msg":"trace[2031667176] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"107.558046ms","start":"2026-04-04T10:19:10.730514Z","end":"2026-04-04T10:19:10.838072Z","steps":["trace[2031667176] 'read index received' (duration: 107.419422ms)","trace[2031667176] 'applied index is now lower than readState.Index' (duration: 137.524µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:10.83823Z","caller":"traceutil/trace.go:171","msg":"trace[805762530] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"176.945678ms","start":"2026-04-04T10:19:10.661273Z","end":"2026-04-04T10:19:10.838219Z","steps":["trace[805762530] 'process raft request' (duration: 176.635449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.8385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.918079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:19:10.838605Z","caller":"traceutil/trace.go:171","msg":"trace[2006652004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"107.036851ms","start":"2026-04-04T10:19:10.731539Z","end":"2026-04-04T10:19:10.838576Z","steps":["trace[2006652004] 'agreement among raft nodes before linearized reading' (duration: 106.895917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:10.838588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.051791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:19:10.838752Z","caller":"traceutil/trace.go:171","msg":"trace[1795840475] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1837; }","duration":"108.291218ms","start":"2026-04-04T10:19:10.730437Z","end":"2026-04-04T10:19:10.838728Z","steps":["trace[1795840475] 'agreement among raft nodes before linearized reading' (duration: 108.037531ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:55.617763Z","caller":"traceutil/trace.go:171","msg":"trace[1065500781] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"210.171353ms","start":"2026-04-04T10:19:55.407548Z","end":"2026-04-04T10:19:55.61772Z","steps":["trace[1065500781] 'process raft request' (duration: 162.167177ms)","trace[1065500781] 'compare' (duration: 47.762439ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:56.048482Z","caller":"traceutil/trace.go:171","msg":"trace[1069185280] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"236.640981ms","start":"2026-04-04T10:19:55.8118Z","end":"2026-04-04T10:19:56.048441Z","steps":["trace[1069185280] 'process raft request' (duration: 162.688062ms)","trace[1069185280] 'compare' (duration: 73.658981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:56.651774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.060602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:19:56.652021Z","caller":"traceutil/trace.go:171","msg":"trace[1741403044] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"487.1869ms","start":"2026-04-04T10:19:56.164788Z","end":"2026-04-04T10:19:56.651974Z","steps":["trace[1741403044] 'process raft request' (duration: 330.84016ms)","trace[1741403044] 'compare' (duration: 155.672131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:56.652216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:56.164763Z","time spent":"487.359986ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:20:01.905958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.196392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-04T10:20:01.906042Z","caller":"traceutil/trace.go:171","msg":"trace[905868246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"107.322545ms","start":"2026-04-04T10:20:01.7987Z","end":"2026-04-04T10:20:01.906022Z","steps":["trace[905868246] 'range keys from in-memory index tree' (duration: 107.023426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:01.906162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.571849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-04T10:20:01.906274Z","caller":"traceutil/trace.go:171","msg":"trace[1333193162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2170; }","duration":"124.735114ms","start":"2026-04-04T10:20:01.781515Z","end":"2026-04-04T10:20:01.90625Z","steps":["trace[1333193162] 'range keys from in-memory index tree' (duration: 124.395525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:01.906381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.264794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:20:01.906577Z","caller":"traceutil/trace.go:171","msg":"trace[894128941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"174.454129ms","start":"2026-04-04T10:20:01.732064Z","end":"2026-04-04T10:20:01.906519Z","steps":["trace[894128941] 'range keys from in-memory index tree' (duration: 174.216352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:50.523027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.041674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:20:50.523132Z","caller":"traceutil/trace.go:171","msg":"trace[1000111152] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2605; }","duration":"106.20321ms","start":"2026-04-04T10:20:50.416905Z","end":"2026-04-04T10:20:50.523109Z","steps":["trace[1000111152] 'count revisions from in-memory index tree' (duration: 105.943562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:50.523394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.045685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-04T10:20:50.523564Z","caller":"traceutil/trace.go:171","msg":"trace[672692772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2605; }","duration":"178.281852ms","start":"2026-04-04T10:20:50.345251Z","end":"2026-04-04T10:20:50.523532Z","steps":["trace[672692772] 'range keys from in-memory index tree' (duration: 177.773867ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:24.197886Z","caller":"traceutil/trace.go:171","msg":"trace[96046697] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2910; }","duration":"159.636428ms","start":"2026-04-04T10:21:24.03821Z","end":"2026-04-04T10:21:24.197847Z","steps":["trace[96046697] 'read index received' (duration: 146.967306ms)","trace[96046697] 'applied index is now lower than readState.Index' (duration: 12.665612ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:21:24.197947Z","caller":"traceutil/trace.go:171","msg":"trace[1564861684] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"247.548493ms","start":"2026-04-04T10:21:23.950356Z","end":"2026-04-04T10:21:24.197905Z","steps":["trace[1564861684] 'process raft request' (duration: 234.83484ms)","trace[1564861684] 'compare' (duration: 12.545068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:21:24.198085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.860784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-04T10:21:24.198127Z","caller":"traceutil/trace.go:171","msg":"trace[329183107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2809; }","duration":"159.955797ms","start":"2026-04-04T10:21:24.038161Z","end":"2026-04-04T10:21:24.198117Z","steps":["trace[329183107] 'agreement among raft nodes before linearized reading' (duration: 159.803033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:21:24.695322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.871873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-04T10:21:24.695396Z","caller":"traceutil/trace.go:171","msg":"trace[318986124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2810; }","duration":"175.996637ms","start":"2026-04-04T10:21:24.519383Z","end":"2026-04-04T10:21:24.69538Z","steps":["trace[318986124] 'range keys from in-memory index tree' (duration: 175.683388ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:24.869184Z","caller":"traceutil/trace.go:171","msg":"trace[329304645] linearizableReadLoop","detail":"{readStateIndex:2913; appliedIndex:2912; }","duration":"138.623497ms","start":"2026-04-04T10:21:24.730537Z","end":"2026-04-04T10:21:24.86916Z","steps":["trace[329304645] 'read index received' (duration: 138.425492ms)","trace[329304645] 'applied index is now lower than readState.Index' (duration: 197.245µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:21:24.869412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.852494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-04T10:21:24.869523Z","caller":"traceutil/trace.go:171","msg":"trace[2051010535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2811; }","duration":"138.980267ms","start":"2026-04-04T10:21:24.730531Z","end":"2026-04-04T10:21:24.869511Z","steps":["trace[2051010535] 'agreement among raft nodes before linearized reading' (duration: 138.72697ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:24.869886Z","caller":"traceutil/trace.go:171","msg":"trace[1846013663] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"171.295032ms","start":"2026-04-04T10:21:24.698579Z","end":"2026-04-04T10:21:24.869874Z","steps":["trace[1846013663] 'process raft request' (duration: 170.455708ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:25.191844Z","caller":"traceutil/trace.go:171","msg":"trace[1295337337] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"318.04357ms","start":"2026-04-04T10:21:24.873776Z","end":"2026-04-04T10:21:25.191819Z","steps":["trace[1295337337] 'process raft request' (duration: 317.897816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:21:25.19197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:21:24.873754Z","time spent":"318.149973ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:21:25.195051Z","caller":"traceutil/trace.go:171","msg":"trace[2142119494] linearizableReadLoop","detail":"{readStateIndex:2916; appliedIndex:2913; }","duration":"142.91415ms","start":"2026-04-04T10:21:25.052126Z","end":"2026-04-04T10:21:25.19504Z","steps":["trace[2142119494] 'read index received' (duration: 139.580535ms)","trace[2142119494] 'applied index is now lower than readState.Index' (duration: 3.332985ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:21:25.195105Z","caller":"traceutil/trace.go:171","msg":"trace[1835289187] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"180.290408ms","start":"2026-04-04T10:21:25.014784Z","end":"2026-04-04T10:21:25.195075Z","steps":["trace[1835289187] 'process raft request' (duration: 180.190795ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:25.195137Z","caller":"traceutil/trace.go:171","msg":"trace[1909114118] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"178.888369ms","start":"2026-04-04T10:21:25.016217Z","end":"2026-04-04T10:21:25.195106Z","steps":["trace[1909114118] 'process raft request' (duration: 178.799476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:21:25.195418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.28708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a3202f23c6fcd4\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-04T10:21:25.195535Z","caller":"traceutil/trace.go:171","msg":"trace[1236473136] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a3202f23c6fcd4; range_end:; response_count:1; response_revision:2814; }","duration":"143.427904ms","start":"2026-04-04T10:21:25.052091Z","end":"2026-04-04T10:21:25.195519Z","steps":["trace[1236473136] 'agreement among raft nodes before linearized reading' (duration: 143.048693ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:27.767332Z","caller":"traceutil/trace.go:171","msg":"trace[922985943] transaction","detail":"{read_only:false; response_revision:3581; number_of_response:1; }","duration":"116.812358ms","start":"2026-04-04T10:23:27.650484Z","end":"2026-04-04T10:23:27.767296Z","steps":["trace[922985943] 'process raft request' (duration: 114.794871ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:25.202815Z","caller":"traceutil/trace.go:171","msg":"trace[1538346661] linearizableReadLoop","detail":"{readStateIndex:5087; appliedIndex:5086; }","duration":"126.191755ms","start":"2026-04-04T10:25:25.076598Z","end":"2026-04-04T10:25:25.20279Z","steps":["trace[1538346661] 'read index received' (duration: 126.065982ms)","trace[1538346661] 'applied index is now lower than readState.Index' (duration: 125.153µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:25.2029Z","caller":"traceutil/trace.go:171","msg":"trace[1591590545] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"160.250856ms","start":"2026-04-04T10:25:25.04263Z","end":"2026-04-04T10:25:25.202881Z","steps":["trace[1591590545] 'process raft request' (duration: 160.016719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:25.202972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.348099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:25:25.203032Z","caller":"traceutil/trace.go:171","msg":"trace[1558990329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4906; }","duration":"126.457783ms","start":"2026-04-04T10:25:25.076561Z","end":"2026-04-04T10:25:25.203019Z","steps":["trace[1558990329] 'agreement among raft nodes before linearized reading' (duration: 126.3492ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:29.379903Z","caller":"traceutil/trace.go:171","msg":"trace[559611371] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5240; }","duration":"129.255642ms","start":"2026-04-04T10:25:29.250626Z","end":"2026-04-04T10:25:29.379881Z","steps":["trace[559611371] 'read index received' (duration: 129.056336ms)","trace[559611371] 'applied index is now lower than readState.Index' (duration: 198.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:29.379976Z","caller":"traceutil/trace.go:171","msg":"trace[633419334] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"159.448123ms","start":"2026-04-04T10:25:29.220507Z","end":"2026-04-04T10:25:29.379956Z","steps":["trace[633419334] 'process raft request' (duration: 159.228477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:29.380172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-04T10:25:29.380251Z","caller":"traceutil/trace.go:171","msg":"trace[1317003030] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5056; }","duration":"129.641753ms","start":"2026-04-04T10:25:29.250592Z","end":"2026-04-04T10:25:29.380234Z","steps":["trace[1317003030] 'agreement among raft nodes before linearized reading' (duration: 129.396986ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:29.534537Z","caller":"traceutil/trace.go:171","msg":"trace[1956000650] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5241; }","duration":"154.538373ms","start":"2026-04-04T10:25:29.379971Z","end":"2026-04-04T10:25:29.53451Z","steps":["trace[1956000650] 'read index received' (duration: 60.00761ms)","trace[1956000650] 'applied index is now lower than readState.Index' (duration: 94.529843ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:29.53456Z","caller":"traceutil/trace.go:171","msg":"trace[437140704] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"209.488289ms","start":"2026-04-04T10:25:29.325045Z","end":"2026-04-04T10:25:29.534533Z","steps":["trace[437140704] 'process raft request' (duration: 114.889373ms)","trace[437140704] 'compare' (duration: 94.460832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:25:29.534735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.55733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-089f4a46-9c25-4136-9fc5-a65b777ef924\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-04-04T10:25:29.534771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.689349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8rqjw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-04T10:25:29.534808Z","caller":"traceutil/trace.go:171","msg":"trace[514529884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-089f4a46-9c25-4136-9fc5-a65b777ef924; range_end:; response_count:1; response_revision:5057; }","duration":"176.668553ms","start":"2026-04-04T10:25:29.358122Z","end":"2026-04-04T10:25:29.53479Z","steps":["trace[514529884] 'agreement among raft nodes before linearized reading' (duration: 176.428466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:29.534818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.904395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-644jr\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-04-04T10:25:29.534834Z","caller":"traceutil/trace.go:171","msg":"trace[875177879] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8rqjw; range_end:; response_count:1; response_revision:5057; }","duration":"206.763421ms","start":"2026-04-04T10:25:29.328053Z","end":"2026-04-04T10:25:29.534816Z","steps":["trace[875177879] 'agreement among raft nodes before linearized reading' (duration: 206.540254ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:29.534868Z","caller":"traceutil/trace.go:171","msg":"trace[1083979678] range","detail":"{range_begin:/registry/pods/monitoring/vector-644jr; range_end:; response_count:1; response_revision:5057; }","duration":"153.04398ms","start":"2026-04-04T10:25:29.38181Z","end":"2026-04-04T10:25:29.534854Z","steps":["trace[1083979678] 'agreement among raft nodes before linearized reading' (duration: 152.913176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:29.535167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.458182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777493"} {"level":"info","ts":"2026-04-04T10:25:29.53523Z","caller":"traceutil/trace.go:171","msg":"trace[95399462] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5057; }","duration":"252.566066ms","start":"2026-04-04T10:25:29.282647Z","end":"2026-04-04T10:25:29.535213Z","steps":["trace[95399462] 'agreement among raft nodes before linearized reading' (duration: 251.959939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:40.719401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.8488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:25:40.719665Z","caller":"traceutil/trace.go:171","msg":"trace[2035010418] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"190.783805ms","start":"2026-04-04T10:25:40.528844Z","end":"2026-04-04T10:25:40.719628Z","steps":["trace[2035010418] 'process raft request' (duration: 61.607915ms)","trace[2035010418] 'compare' (duration: 128.645495ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:40.719801Z","caller":"traceutil/trace.go:171","msg":"trace[2095285601] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5460; }","duration":"189.681905ms","start":"2026-04-04T10:25:40.53011Z","end":"2026-04-04T10:25:40.719792Z","steps":["trace[2095285601] 'read index received' (duration: 60.34686ms)","trace[2095285601] 'applied index is now lower than readState.Index' (duration: 129.333775ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:40.719753Z","caller":"traceutil/trace.go:171","msg":"trace[902420510] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"105.799084ms","start":"2026-04-04T10:25:40.613911Z","end":"2026-04-04T10:25:40.71971Z","steps":["trace[902420510] 'process raft request' (duration: 105.747253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:40.720214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.071275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-04T10:25:40.720313Z","caller":"traceutil/trace.go:171","msg":"trace[1261511227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5273; }","duration":"190.21457ms","start":"2026-04-04T10:25:40.530079Z","end":"2026-04-04T10:25:40.720294Z","steps":["trace[1261511227] 'agreement among raft nodes before linearized reading' (duration: 189.86816ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:40.720321Z","caller":"traceutil/trace.go:171","msg":"trace[1854396629] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"189.344535ms","start":"2026-04-04T10:25:40.530963Z","end":"2026-04-04T10:25:40.720308Z","steps":["trace[1854396629] 'process raft request' (duration: 188.581284ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:40.720504Z","caller":"traceutil/trace.go:171","msg":"trace[76732544] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"186.016169ms","start":"2026-04-04T10:25:40.534477Z","end":"2026-04-04T10:25:40.720493Z","steps":["trace[76732544] 'process raft request' (duration: 185.149985ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:41.219849Z","caller":"traceutil/trace.go:171","msg":"trace[387075928] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"297.980019ms","start":"2026-04-04T10:25:40.921849Z","end":"2026-04-04T10:25:41.219829Z","steps":["trace[387075928] 'process raft request' (duration: 297.925858ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:41.219987Z","caller":"traceutil/trace.go:171","msg":"trace[653678165] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"298.322029ms","start":"2026-04-04T10:25:40.921624Z","end":"2026-04-04T10:25:41.219946Z","steps":["trace[653678165] 'process raft request' (duration: 297.983529ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:41.220072Z","caller":"traceutil/trace.go:171","msg":"trace[2023573013] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"298.223906ms","start":"2026-04-04T10:25:40.921832Z","end":"2026-04-04T10:25:41.220056Z","steps":["trace[2023573013] 'process raft request' (duration: 297.905357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:49.417633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.540859ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:25:49.417814Z","caller":"traceutil/trace.go:171","msg":"trace[899789094] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5454; }","duration":"124.740685ms","start":"2026-04-04T10:25:49.293053Z","end":"2026-04-04T10:25:49.417793Z","steps":["trace[899789094] 'range keys from in-memory index tree' (duration: 124.519418ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:27:02.445062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3019} {"level":"info","ts":"2026-04-04T10:27:02.526121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3019,"took":"79.47217ms","hash":2634984489,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-04T10:27:02.526208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2634984489,"revision":3019,"compact-revision":-1} {"level":"info","ts":"2026-04-04T10:31:52.655743Z","caller":"traceutil/trace.go:171","msg":"trace[1679634084] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"166.755419ms","start":"2026-04-04T10:31:52.48896Z","end":"2026-04-04T10:31:52.655716Z","steps":["trace[1679634084] 'process raft request' (duration: 166.606275ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:02.45652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-04-04T10:32:02.550133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"91.831091ms","hash":3917668149,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-04T10:32:02.550205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3917668149,"revision":6119,"compact-revision":3019} {"level":"info","ts":"2026-04-04T10:32:06.668348Z","caller":"traceutil/trace.go:171","msg":"trace[884187098] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"108.503735ms","start":"2026-04-04T10:32:06.559825Z","end":"2026-04-04T10:32:06.668329Z","steps":["trace[884187098] 'process raft request' (duration: 108.369982ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:33.857586Z","caller":"traceutil/trace.go:171","msg":"trace[231642114] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"285.745315ms","start":"2026-04-04T10:32:33.571817Z","end":"2026-04-04T10:32:33.857562Z","steps":["trace[231642114] 'process raft request' (duration: 285.604601ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:33.879804Z","caller":"traceutil/trace.go:171","msg":"trace[1912266381] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"272.794416ms","start":"2026-04-04T10:32:33.606978Z","end":"2026-04-04T10:32:33.879772Z","steps":["trace[1912266381] 'process raft request' (duration: 272.624491ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:33.880016Z","caller":"traceutil/trace.go:171","msg":"trace[1679981069] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8829; }","duration":"148.080696ms","start":"2026-04-04T10:32:33.731888Z","end":"2026-04-04T10:32:33.879969Z","steps":["trace[1679981069] 'read index received' (duration: 125.558463ms)","trace[1679981069] 'applied index is now lower than readState.Index' (duration: 22.519133ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:33.880097Z","caller":"traceutil/trace.go:171","msg":"trace[745326385] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"176.685163ms","start":"2026-04-04T10:32:33.703401Z","end":"2026-04-04T10:32:33.880086Z","steps":["trace[745326385] 'process raft request' (duration: 176.326592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:33.880183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.270292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:32:33.880228Z","caller":"traceutil/trace.go:171","msg":"trace[1497590275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8538; }","duration":"148.372554ms","start":"2026-04-04T10:32:33.731845Z","end":"2026-04-04T10:32:33.880217Z","steps":["trace[1497590275] 'agreement among raft nodes before linearized reading' (duration: 148.265421ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:34.057699Z","caller":"traceutil/trace.go:171","msg":"trace[1164131281] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"169.675221ms","start":"2026-04-04T10:32:33.887994Z","end":"2026-04-04T10:32:34.057669Z","steps":["trace[1164131281] 'process raft request' (duration: 169.298751ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:46.500269Z","caller":"traceutil/trace.go:171","msg":"trace[2062890817] linearizableReadLoop","detail":"{readStateIndex:8947; appliedIndex:8946; }","duration":"181.065198ms","start":"2026-04-04T10:32:46.319177Z","end":"2026-04-04T10:32:46.500242Z","steps":["trace[2062890817] 'read index received' (duration: 109.338761ms)","trace[2062890817] 'applied index is now lower than readState.Index' (duration: 71.725407ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:46.50037Z","caller":"traceutil/trace.go:171","msg":"trace[226192355] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"182.495847ms","start":"2026-04-04T10:32:46.317832Z","end":"2026-04-04T10:32:46.500328Z","steps":["trace[226192355] 'process raft request' (duration: 110.666287ms)","trace[226192355] 'compare' (duration: 71.609964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:32:46.50051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.315375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-x727j\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-04-04T10:32:46.500686Z","caller":"traceutil/trace.go:171","msg":"trace[966646279] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-x727j; range_end:; response_count:1; response_revision:8646; }","duration":"181.466069ms","start":"2026-04-04T10:32:46.319168Z","end":"2026-04-04T10:32:46.500634Z","steps":["trace[966646279] 'agreement among raft nodes before linearized reading' (duration: 181.205292ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:46.531585Z","caller":"traceutil/trace.go:171","msg":"trace[1661349136] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8646; }","duration":"209.834298ms","start":"2026-04-04T10:32:46.321712Z","end":"2026-04-04T10:32:46.531547Z","steps":["trace[1661349136] 'process raft request' (duration: 209.703765ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:47.375282Z","caller":"traceutil/trace.go:171","msg":"trace[1565713617] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8951; }","duration":"239.540775ms","start":"2026-04-04T10:32:47.135711Z","end":"2026-04-04T10:32:47.375252Z","steps":["trace[1565713617] 'read index received' (duration: 55.518604ms)","trace[1565713617] 'applied index is now lower than readState.Index' (duration: 184.021211ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:47.375329Z","caller":"traceutil/trace.go:171","msg":"trace[1239617708] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"267.808942ms","start":"2026-04-04T10:32:47.107477Z","end":"2026-04-04T10:32:47.375286Z","steps":["trace[1239617708] 'process raft request' (duration: 229.50496ms)","trace[1239617708] 'compare' (duration: 38.140347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:32:47.375504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.783692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-04T10:32:47.375568Z","caller":"traceutil/trace.go:171","msg":"trace[1802590011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8651; }","duration":"239.898456ms","start":"2026-04-04T10:32:47.135655Z","end":"2026-04-04T10:32:47.375554Z","steps":["trace[1802590011] 'agreement among raft nodes before linearized reading' (duration: 239.70929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:47.393922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.150987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-04T10:32:47.393986Z","caller":"traceutil/trace.go:171","msg":"trace[389417837] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8653; }","duration":"121.270221ms","start":"2026-04-04T10:32:47.272702Z","end":"2026-04-04T10:32:47.393972Z","steps":["trace[389417837] 'agreement among raft nodes before linearized reading' (duration: 121.088086ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:48.133993Z","caller":"traceutil/trace.go:171","msg":"trace[271391510] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"131.617536ms","start":"2026-04-04T10:32:48.002349Z","end":"2026-04-04T10:32:48.133966Z","steps":["trace[271391510] 'read index received' (duration: 131.447481ms)","trace[271391510] 'applied index is now lower than readState.Index' (duration: 168.945µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:48.134082Z","caller":"traceutil/trace.go:171","msg":"trace[1255397553] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"278.180599ms","start":"2026-04-04T10:32:47.855874Z","end":"2026-04-04T10:32:48.134054Z","steps":["trace[1255397553] 'process raft request' (duration: 277.917181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:48.134201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.826172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-04T10:32:48.134239Z","caller":"traceutil/trace.go:171","msg":"trace[1416584780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8658; }","duration":"131.915554ms","start":"2026-04-04T10:32:48.002311Z","end":"2026-04-04T10:32:48.134227Z","steps":["trace[1416584780] 'agreement among raft nodes before linearized reading' (duration: 131.75995ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:48.252947Z","caller":"traceutil/trace.go:171","msg":"trace[747508223] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"249.600185ms","start":"2026-04-04T10:32:48.003319Z","end":"2026-04-04T10:32:48.252919Z","steps":["trace[747508223] 'process raft request' (duration: 236.426668ms)","trace[747508223] 'compare' (duration: 13.012271ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:48.253584Z","caller":"traceutil/trace.go:171","msg":"trace[195519559] linearizableReadLoop","detail":"{readStateIndex:8963; appliedIndex:8960; }","duration":"117.467273ms","start":"2026-04-04T10:32:48.136099Z","end":"2026-04-04T10:32:48.253566Z","steps":["trace[195519559] 'read index received' (duration: 103.657928ms)","trace[195519559] 'applied index is now lower than readState.Index' (duration: 13.808175ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:48.253837Z","caller":"traceutil/trace.go:171","msg":"trace[1328038761] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"121.363483ms","start":"2026-04-04T10:32:48.132441Z","end":"2026-04-04T10:32:48.253804Z","steps":["trace[1328038761] 'process raft request' (duration: 120.584332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:48.254047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.926226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-04T10:32:48.254132Z","caller":"traceutil/trace.go:171","msg":"trace[690335474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8661; }","duration":"118.052089ms","start":"2026-04-04T10:32:48.136067Z","end":"2026-04-04T10:32:48.254119Z","steps":["trace[690335474] 'agreement among raft nodes before linearized reading' (duration: 117.825273ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:48.25434Z","caller":"traceutil/trace.go:171","msg":"trace[366243805] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"134.172249ms","start":"2026-04-04T10:32:48.118952Z","end":"2026-04-04T10:32:48.253124Z","steps":["trace[366243805] 'process raft request' (duration: 133.911002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:48.278079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.024399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8rqjw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-04T10:32:48.27816Z","caller":"traceutil/trace.go:171","msg":"trace[2016879529] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8rqjw; range_end:; response_count:1; response_revision:8662; }","duration":"118.156223ms","start":"2026-04-04T10:32:48.159986Z","end":"2026-04-04T10:32:48.278142Z","steps":["trace[2016879529] 'agreement among raft nodes before linearized reading' (duration: 117.941206ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:48.278325Z","caller":"traceutil/trace.go:171","msg":"trace[1786669116] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"140.030526ms","start":"2026-04-04T10:32:48.138257Z","end":"2026-04-04T10:32:48.278287Z","steps":["trace[1786669116] 'process raft request' (duration: 139.539191ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:33:00.278911Z","caller":"traceutil/trace.go:171","msg":"trace[1698925261] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"112.413258ms","start":"2026-04-04T10:33:00.166474Z","end":"2026-04-04T10:33:00.278888Z","steps":["trace[1698925261] 'process raft request' (duration: 109.361041ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:33:30.975883Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c6.wal"} {"level":"info","ts":"2026-04-04T10:33:57.650542Z","caller":"traceutil/trace.go:171","msg":"trace[1646656158] transaction","detail":"{read_only:false; response_revision:9369; number_of_response:1; }","duration":"103.104455ms","start":"2026-04-04T10:33:57.547411Z","end":"2026-04-04T10:33:57.650516Z","steps":["trace[1646656158] 'process raft request' (duration: 102.952931ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:33:57.887569Z","caller":"traceutil/trace.go:171","msg":"trace[1281523955] transaction","detail":"{read_only:false; response_revision:9371; number_of_response:1; }","duration":"106.235824ms","start":"2026-04-04T10:33:57.781294Z","end":"2026-04-04T10:33:57.88753Z","steps":["trace[1281523955] 'process raft request' (duration: 80.664821ms)","trace[1281523955] 'compare' (duration: 25.444089ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:34:37.499331Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T10:34:37.506853Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-04T10:34:37.506932Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-04T10:36:13.548542Z","caller":"traceutil/trace.go:171","msg":"trace[307956102] transaction","detail":"{read_only:false; response_revision:10412; number_of_response:1; }","duration":"118.836141ms","start":"2026-04-04T10:36:13.429668Z","end":"2026-04-04T10:36:13.548504Z","steps":["trace[307956102] 'process raft request' (duration: 116.878955ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:37:02.463778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8315} {"level":"info","ts":"2026-04-04T10:37:02.546209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8315,"took":"80.089153ms","hash":3417661483,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":25804800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-04T10:37:02.54629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3417661483,"revision":8315,"compact-revision":6119} {"level":"info","ts":"2026-04-04T10:42:02.471012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10718} {"level":"info","ts":"2026-04-04T10:42:02.548371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10718,"took":"75.282617ms","hash":1732827482,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":20549632,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-04T10:42:02.548436Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1732827482,"revision":10718,"compact-revision":8315}