{"level":"warn","ts":"2026-09-25T15:01:25.304724Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T15:01:25.304852Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-09-25T15:01:25.304978Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T15:01:25.305001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-09-25T15:01:25.305054Z","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-09-25T15:01:25.305987Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-09-25T15:01:25.306116Z","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.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-09-25T15:01:25.313645Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.089744ms"} {"level":"info","ts":"2026-09-25T15:01:25.321526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-09-25T15:01:25.321627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-09-25T15:01:25.321656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-09-25T15:01:25.321674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-25T15:01:25.321689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-09-25T15:01:25.321737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-09-25T15:01:25.332047Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-25T15:01:25.334349Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-25T15:01:25.336143Z","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-09-25T15:01:25.340974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-25T15:01:25.342184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-25T15:01:25.342577Z","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-09-25T15:01:25.342649Z","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-09-25T15:01:25.342826Z","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-09-25T15:01:25.342829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-09-25T15:01:25.343119Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-09-25T15:01:25.350284Z","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-09-25T15:01:25.350434Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-09-25T15:01:25.350509Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-09-25T15:01:25.350822Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-25T15:01:25.35086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-25T15:01:25.922522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-09-25T15:01:25.922622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-09-25T15:01:25.922654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-09-25T15:01:25.922674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-09-25T15:01:25.922685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-09-25T15:01:25.922716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-09-25T15:01:25.922736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-09-25T15:01:25.938342Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:01:25.946282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T15:01:25.94629Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-25T15:01:25.946315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T15:01:25.94654Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:01:25.946641Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-25T15:01:25.946654Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:01:25.946667Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-25T15:01:25.946698Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:01:25.948113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-09-25T15:01:25.951039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-25T15:01:29.352209Z","caller":"traceutil/trace.go:171","msg":"trace[907087224] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"106.505186ms","start":"2026-09-25T15:01:29.245678Z","end":"2026-09-25T15:01:29.352183Z","steps":["trace[907087224] 'process raft request' (duration: 95.742735ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:06.703372Z","caller":"traceutil/trace.go:171","msg":"trace[714031107] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"336.142656ms","start":"2026-09-25T15:02:06.367194Z","end":"2026-09-25T15:02:06.703336Z","steps":["trace[714031107] 'process raft request' (duration: 336.004351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.704174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.367167Z","time spent":"336.294591ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.761225Z","caller":"traceutil/trace.go:171","msg":"trace[845325437] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"382.449839ms","start":"2026-09-25T15:02:06.378734Z","end":"2026-09-25T15:02:06.761184Z","steps":["trace[845325437] 'process raft request' (duration: 382.128517ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:06.76135Z","caller":"traceutil/trace.go:171","msg":"trace[821646405] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"377.187814ms","start":"2026-09-25T15:02:06.384081Z","end":"2026-09-25T15:02:06.761269Z","steps":["trace[821646405] 'process raft request' (duration: 376.915134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.761409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.378716Z","time spent":"382.606815ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-25T15:02:06.761567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.384065Z","time spent":"377.397762ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.761629Z","caller":"traceutil/trace.go:171","msg":"trace[1195875057] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"368.365398ms","start":"2026-09-25T15:02:06.393177Z","end":"2026-09-25T15:02:06.761543Z","steps":["trace[1195875057] 'process raft request' (duration: 367.86299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.761881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.393167Z","time spent":"368.572736ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.762461Z","caller":"traceutil/trace.go:171","msg":"trace[567362718] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"364.958293ms","start":"2026-09-25T15:02:06.397452Z","end":"2026-09-25T15:02:06.76241Z","steps":["trace[567362718] 'process raft request' (duration: 364.653991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.762738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.397442Z","time spent":"365.255093ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.762891Z","caller":"traceutil/trace.go:171","msg":"trace[540106351] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"359.946967ms","start":"2026-09-25T15:02:06.402919Z","end":"2026-09-25T15:02:06.762866Z","steps":["trace[540106351] 'process raft request' (duration: 359.47273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.762989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.402911Z","time spent":"360.03386ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.763173Z","caller":"traceutil/trace.go:171","msg":"trace[698018371] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"365.654058ms","start":"2026-09-25T15:02:06.397505Z","end":"2026-09-25T15:02:06.763159Z","steps":["trace[698018371] 'process raft request' (duration: 364.688802ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:06.763213Z","caller":"traceutil/trace.go:171","msg":"trace[1702316102] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"202.306284ms","start":"2026-09-25T15:02:06.560886Z","end":"2026-09-25T15:02:06.763192Z","steps":["trace[1702316102] 'process raft request' (duration: 202.221201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.763236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.3975Z","time spent":"365.70886ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.763379Z","caller":"traceutil/trace.go:171","msg":"trace[1423416380] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"342.542313ms","start":"2026-09-25T15:02:06.420821Z","end":"2026-09-25T15:02:06.763364Z","steps":["trace[1423416380] 'process raft request' (duration: 341.987472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.763441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.420794Z","time spent":"342.619456ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.763514Z","caller":"traceutil/trace.go:171","msg":"trace[819855614] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"332.224761ms","start":"2026-09-25T15:02:06.431272Z","end":"2026-09-25T15:02:06.763497Z","steps":["trace[819855614] 'process raft request' (duration: 331.619908ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:06.76354Z","caller":"traceutil/trace.go:171","msg":"trace[1440808537] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"354.872019ms","start":"2026-09-25T15:02:06.408655Z","end":"2026-09-25T15:02:06.763527Z","steps":["trace[1440808537] 'process raft request' (duration: 354.059249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.763592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.40863Z","time spent":"354.939211ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-25T15:02:06.763606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.431249Z","time spent":"332.309714ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:06.764308Z","caller":"traceutil/trace.go:171","msg":"trace[580285452] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"360.359322ms","start":"2026-09-25T15:02:06.402969Z","end":"2026-09-25T15:02:06.763329Z","steps":["trace[580285452] 'process raft request' (duration: 359.496891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:06.764406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:02:06.402965Z","time spent":"361.398481ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T15:02:07.078606Z","caller":"traceutil/trace.go:171","msg":"trace[1679198665] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"159.499253ms","start":"2026-09-25T15:02:06.919072Z","end":"2026-09-25T15:02:07.078571Z","steps":["trace[1679198665] 'process raft request' (duration: 159.072957ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:07.078827Z","caller":"traceutil/trace.go:171","msg":"trace[330408808] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:539; }","duration":"162.289606ms","start":"2026-09-25T15:02:06.916519Z","end":"2026-09-25T15:02:07.078808Z","steps":["trace[330408808] 'read index received' (duration: 52.075906ms)","trace[330408808] 'applied index is now lower than readState.Index' (duration: 110.210109ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:07.079246Z","caller":"traceutil/trace.go:171","msg":"trace[1116701870] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"161.790307ms","start":"2026-09-25T15:02:06.916464Z","end":"2026-09-25T15:02:07.078255Z","steps":["trace[1116701870] 'process raft request' (duration: 110.990808ms)","trace[1116701870] 'compare' (duration: 50.54705ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:07.07927Z","caller":"traceutil/trace.go:171","msg":"trace[1410940701] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"141.386193ms","start":"2026-09-25T15:02:06.937869Z","end":"2026-09-25T15:02:07.079255Z","steps":["trace[1410940701] 'process raft request' (duration: 140.76125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:07.079336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.790674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-25T15:02:07.079648Z","caller":"traceutil/trace.go:171","msg":"trace[1400150606] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:521; }","duration":"163.133346ms","start":"2026-09-25T15:02:06.91649Z","end":"2026-09-25T15:02:07.079623Z","steps":["trace[1400150606] 'agreement among raft nodes before linearized reading' (duration: 162.782464ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:07.07942Z","caller":"traceutil/trace.go:171","msg":"trace[812620040] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"157.565271ms","start":"2026-09-25T15:02:06.921842Z","end":"2026-09-25T15:02:07.079407Z","steps":["trace[812620040] 'process raft request' (duration: 156.552183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:07.082649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.084525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-09-25T15:02:07.08276Z","caller":"traceutil/trace.go:171","msg":"trace[813363924] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:522; }","duration":"166.22168ms","start":"2026-09-25T15:02:06.916523Z","end":"2026-09-25T15:02:07.082745Z","steps":["trace[813363924] 'agreement among raft nodes before linearized reading' (duration: 166.039523ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:07.351322Z","caller":"traceutil/trace.go:171","msg":"trace[544168758] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"102.489991ms","start":"2026-09-25T15:02:07.248789Z","end":"2026-09-25T15:02:07.351279Z","steps":["trace[544168758] 'process raft request' (duration: 87.176991ms)","trace[544168758] 'compare' (duration: 14.961197ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:13.147426Z","caller":"traceutil/trace.go:171","msg":"trace[1758940099] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"117.486208ms","start":"2026-09-25T15:02:13.029905Z","end":"2026-09-25T15:02:13.147391Z","steps":["trace[1758940099] 'process raft request' (duration: 116.794604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:13.375843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.632237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:02:13.376028Z","caller":"traceutil/trace.go:171","msg":"trace[1780996913] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"224.198194ms","start":"2026-09-25T15:02:13.151797Z","end":"2026-09-25T15:02:13.375995Z","steps":["trace[1780996913] 'process raft request' (duration: 90.992457ms)","trace[1780996913] 'compare' (duration: 132.480412ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:13.376089Z","caller":"traceutil/trace.go:171","msg":"trace[380355711] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"137.125582ms","start":"2026-09-25T15:02:13.238943Z","end":"2026-09-25T15:02:13.376069Z","steps":["trace[380355711] 'process raft request' (duration: 137.060179ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:13.376272Z","caller":"traceutil/trace.go:171","msg":"trace[2114068303] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"212.938577ms","start":"2026-09-25T15:02:13.163323Z","end":"2026-09-25T15:02:13.376262Z","steps":["trace[2114068303] 'read index received' (duration: 79.610776ms)","trace[2114068303] 'applied index is now lower than readState.Index' (duration: 133.325831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:02:13.376389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.045471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T15:02:13.376419Z","caller":"traceutil/trace.go:171","msg":"trace[1807660302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"213.122543ms","start":"2026-09-25T15:02:13.163286Z","end":"2026-09-25T15:02:13.376408Z","steps":["trace[1807660302] 'agreement among raft nodes before linearized reading' (duration: 213.03186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:13.37656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.651033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-25T15:02:13.376583Z","caller":"traceutil/trace.go:171","msg":"trace[1525520049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"211.692474ms","start":"2026-09-25T15:02:13.164885Z","end":"2026-09-25T15:02:13.376577Z","steps":["trace[1525520049] 'agreement among raft nodes before linearized reading' (duration: 211.621652ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:13.568813Z","caller":"traceutil/trace.go:171","msg":"trace[1080425896] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"183.065871ms","start":"2026-09-25T15:02:13.385712Z","end":"2026-09-25T15:02:13.568778Z","steps":["trace[1080425896] 'process raft request' (duration: 137.206995ms)","trace[1080425896] 'compare' (duration: 45.68708ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:13.579709Z","caller":"traceutil/trace.go:171","msg":"trace[1094493765] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"188.87906ms","start":"2026-09-25T15:02:13.390801Z","end":"2026-09-25T15:02:13.57968Z","steps":["trace[1094493765] 'read index received' (duration: 132.13221ms)","trace[1094493765] 'applied index is now lower than readState.Index' (duration: 56.7457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:02:13.579894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.044596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:02:13.579936Z","caller":"traceutil/trace.go:171","msg":"trace[763804814] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:603; }","duration":"189.14688ms","start":"2026-09-25T15:02:13.390775Z","end":"2026-09-25T15:02:13.579922Z","steps":["trace[763804814] 'agreement among raft nodes before linearized reading' (duration: 189.015755ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:13.579987Z","caller":"traceutil/trace.go:171","msg":"trace[1191319636] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"194.001426ms","start":"2026-09-25T15:02:13.385969Z","end":"2026-09-25T15:02:13.57997Z","steps":["trace[1191319636] 'process raft request' (duration: 193.466878ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:13.580187Z","caller":"traceutil/trace.go:171","msg":"trace[296943274] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"182.895605ms","start":"2026-09-25T15:02:13.397259Z","end":"2026-09-25T15:02:13.580154Z","steps":["trace[296943274] 'process raft request' (duration: 182.356006ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:14.154975Z","caller":"traceutil/trace.go:171","msg":"trace[448577795] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"204.172944ms","start":"2026-09-25T15:02:13.950773Z","end":"2026-09-25T15:02:14.154945Z","steps":["trace[448577795] 'process raft request' (duration: 203.512121ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:15.670554Z","caller":"traceutil/trace.go:171","msg":"trace[1569180878] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"125.094812ms","start":"2026-09-25T15:02:15.545428Z","end":"2026-09-25T15:02:15.670523Z","steps":["trace[1569180878] 'process raft request' (duration: 124.902005ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:15.675659Z","caller":"traceutil/trace.go:171","msg":"trace[1337707791] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"101.561328ms","start":"2026-09-25T15:02:15.574069Z","end":"2026-09-25T15:02:15.67563Z","steps":["trace[1337707791] 'read index received' (duration: 96.267591ms)","trace[1337707791] 'applied index is now lower than readState.Index' (duration: 5.292627ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:15.675908Z","caller":"traceutil/trace.go:171","msg":"trace[1743985817] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"130.139821ms","start":"2026-09-25T15:02:15.545711Z","end":"2026-09-25T15:02:15.675851Z","steps":["trace[1743985817] 'process raft request' (duration: 129.798759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:15.676044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.788867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T15:02:15.676206Z","caller":"traceutil/trace.go:171","msg":"trace[567857578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"102.097077ms","start":"2026-09-25T15:02:15.57403Z","end":"2026-09-25T15:02:15.676127Z","steps":["trace[567857578] 'agreement among raft nodes before linearized reading' (duration: 101.737215ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:26.655958Z","caller":"traceutil/trace.go:171","msg":"trace[454165017] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"102.777962ms","start":"2026-09-25T15:02:26.553148Z","end":"2026-09-25T15:02:26.655926Z","steps":["trace[454165017] 'process raft request' (duration: 73.463107ms)","trace[454165017] 'compare' (duration: 29.003046ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:26.656538Z","caller":"traceutil/trace.go:171","msg":"trace[309711468] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"101.107641ms","start":"2026-09-25T15:02:26.555419Z","end":"2026-09-25T15:02:26.656527Z","steps":["trace[309711468] 'process raft request' (duration: 101.024059ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:37.945456Z","caller":"traceutil/trace.go:171","msg":"trace[933387736] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"118.682264ms","start":"2026-09-25T15:02:37.826741Z","end":"2026-09-25T15:02:37.945424Z","steps":["trace[933387736] 'read index received' (duration: 118.393976ms)","trace[933387736] 'applied index is now lower than readState.Index' (duration: 287.008µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:02:37.945576Z","caller":"traceutil/trace.go:171","msg":"trace[1022240958] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"214.161976ms","start":"2026-09-25T15:02:37.731375Z","end":"2026-09-25T15:02:37.945537Z","steps":["trace[1022240958] 'process raft request' (duration: 213.813196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:02:37.946057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.2551ms","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-09-25T15:02:37.946195Z","caller":"traceutil/trace.go:171","msg":"trace[252482234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"119.486386ms","start":"2026-09-25T15:02:37.826685Z","end":"2026-09-25T15:02:37.946172Z","steps":["trace[252482234] 'agreement among raft nodes before linearized reading' (duration: 118.914461ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:38.052636Z","caller":"traceutil/trace.go:171","msg":"trace[23253114] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"101.129904ms","start":"2026-09-25T15:02:37.951474Z","end":"2026-09-25T15:02:38.052604Z","steps":["trace[23253114] 'process raft request' (duration: 95.42728ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:02:38.053142Z","caller":"traceutil/trace.go:171","msg":"trace[590040225] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"101.533895ms","start":"2026-09-25T15:02:37.951524Z","end":"2026-09-25T15:02:38.053057Z","steps":["trace[590040225] 'process raft request' (duration: 101.031401ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:03:07.892935Z","caller":"traceutil/trace.go:171","msg":"trace[1528032704] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"140.278635ms","start":"2026-09-25T15:03:07.752628Z","end":"2026-09-25T15:03:07.892907Z","steps":["trace[1528032704] 'process raft request' (duration: 126.880978ms)","trace[1528032704] 'compare' (duration: 13.258254ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:03:08.671431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.101356ms","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-09-25T15:03:08.671581Z","caller":"traceutil/trace.go:171","msg":"trace[1339165126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1192; }","duration":"176.30539ms","start":"2026-09-25T15:03:08.495256Z","end":"2026-09-25T15:03:08.671561Z","steps":["trace[1339165126] 'range keys from in-memory index tree' (duration: 175.938442ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:03:30.924648Z","caller":"traceutil/trace.go:171","msg":"trace[1700989903] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"127.011948ms","start":"2026-09-25T15:03:30.797618Z","end":"2026-09-25T15:03:30.92463Z","steps":["trace[1700989903] 'process raft request' (duration: 126.913097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:03:56.21515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.096109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62a6a58-3cd8-40ad-8e6c-d0566d2d7499\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-25T15:03:56.215273Z","caller":"traceutil/trace.go:171","msg":"trace[2029330303] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c62a6a58-3cd8-40ad-8e6c-d0566d2d7499; range_end:; response_count:1; response_revision:1851; }","duration":"321.297712ms","start":"2026-09-25T15:03:55.893948Z","end":"2026-09-25T15:03:56.215246Z","steps":["trace[2029330303] 'range keys from in-memory index tree' (duration: 320.859165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:03:56.215341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:03:55.893921Z","time spent":"321.404854ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62a6a58-3cd8-40ad-8e6c-d0566d2d7499\" "} {"level":"warn","ts":"2026-09-25T15:03:56.215321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.07871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:03:56.215479Z","caller":"traceutil/trace.go:171","msg":"trace[448368039] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"271.270957ms","start":"2026-09-25T15:03:55.944164Z","end":"2026-09-25T15:03:56.215435Z","steps":["trace[448368039] 'read index received' (duration: 84.990354ms)","trace[448368039] 'applied index is now lower than readState.Index' (duration: 186.277273ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:03:56.215523Z","caller":"traceutil/trace.go:171","msg":"trace[451875708] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"275.551897ms","start":"2026-09-25T15:03:55.939951Z","end":"2026-09-25T15:03:56.215503Z","steps":["trace[451875708] 'process raft request' (duration: 89.204862ms)","trace[451875708] 'compare' (duration: 185.966858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:03:56.21566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.485971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-09-25T15:03:56.215833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.760137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62a6a58-3cd8-40ad-8e6c-d0566d2d7499\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-25T15:03:56.215883Z","caller":"traceutil/trace.go:171","msg":"trace[1444597969] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c62a6a58-3cd8-40ad-8e6c-d0566d2d7499; range_end:; response_count:1; response_revision:1852; }","duration":"257.851598ms","start":"2026-09-25T15:03:55.958015Z","end":"2026-09-25T15:03:56.215867Z","steps":["trace[1444597969] 'agreement among raft nodes before linearized reading' (duration: 257.699116ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:03:56.215876Z","caller":"traceutil/trace.go:171","msg":"trace[1891845611] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1852; }","duration":"271.718584ms","start":"2026-09-25T15:03:55.944127Z","end":"2026-09-25T15:03:56.215845Z","steps":["trace[1891845611] 'agreement among raft nodes before linearized reading' (duration: 271.43682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:03:56.430215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.425071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:03:56.430614Z","caller":"traceutil/trace.go:171","msg":"trace[954341198] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"206.707735ms","start":"2026-09-25T15:03:56.223834Z","end":"2026-09-25T15:03:56.430541Z","steps":["trace[954341198] 'process raft request' (duration: 206.483001ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:03:56.430923Z","caller":"traceutil/trace.go:171","msg":"trace[873197836] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"209.085373ms","start":"2026-09-25T15:03:56.221791Z","end":"2026-09-25T15:03:56.430877Z","steps":["trace[873197836] 'process raft request' (duration: 94.8966ms)","trace[873197836] 'compare' (duration: 113.180867ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:03:56.745209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.036753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:03:56.745334Z","caller":"traceutil/trace.go:171","msg":"trace[1289406382] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"173.951073ms","start":"2026-09-25T15:03:56.571336Z","end":"2026-09-25T15:03:56.745287Z","steps":["trace[1289406382] 'read index received' (duration: 49.769798ms)","trace[1289406382] 'applied index is now lower than readState.Index' (duration: 124.180305ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:03:56.74557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.726116ms","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-09-25T15:03:56.745594Z","caller":"traceutil/trace.go:171","msg":"trace[768020466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1856; }","duration":"137.776076ms","start":"2026-09-25T15:03:56.607811Z","end":"2026-09-25T15:03:56.745587Z","steps":["trace[768020466] 'agreement among raft nodes before linearized reading' (duration: 137.653974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:03:56.745538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.195707ms","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-09-25T15:03:56.745679Z","caller":"traceutil/trace.go:171","msg":"trace[236602368] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"174.38605ms","start":"2026-09-25T15:03:56.571275Z","end":"2026-09-25T15:03:56.745661Z","steps":["trace[236602368] 'agreement among raft nodes before linearized reading' (duration: 174.119776ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:03:56.745797Z","caller":"traceutil/trace.go:171","msg":"trace[275074579] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"247.482747ms","start":"2026-09-25T15:03:56.498255Z","end":"2026-09-25T15:03:56.745738Z","steps":["trace[275074579] 'process raft request' (duration: 122.845204ms)","trace[275074579] 'compare' (duration: 123.918571ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:03:56.745651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.93684ms","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-09-25T15:03:56.746453Z","caller":"traceutil/trace.go:171","msg":"trace[858501116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"135.733903ms","start":"2026-09-25T15:03:56.61065Z","end":"2026-09-25T15:03:56.746384Z","steps":["trace[858501116] 'agreement among raft nodes before linearized reading' (duration: 134.809658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:30.240364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.288829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:04:30.240462Z","caller":"traceutil/trace.go:171","msg":"trace[2078582131] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"184.221189ms","start":"2026-09-25T15:04:30.056223Z","end":"2026-09-25T15:04:30.240444Z","steps":["trace[2078582131] 'process raft request' (duration: 61.751748ms)","trace[2078582131] 'compare' (duration: 122.185207ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:04:30.469974Z","caller":"traceutil/trace.go:171","msg":"trace[106033180] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"209.221049ms","start":"2026-09-25T15:04:30.260704Z","end":"2026-09-25T15:04:30.469925Z","steps":["trace[106033180] 'process raft request' (duration: 192.216164ms)","trace[106033180] 'compare' (duration: 16.454117ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:04:30.537452Z","caller":"traceutil/trace.go:171","msg":"trace[2054684118] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"170.934108ms","start":"2026-09-25T15:04:30.366498Z","end":"2026-09-25T15:04:30.537433Z","steps":["trace[2054684118] 'process raft request' (duration: 170.711865ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:04:30.537556Z","caller":"traceutil/trace.go:171","msg":"trace[428499332] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2179; }","duration":"113.491462ms","start":"2026-09-25T15:04:30.42384Z","end":"2026-09-25T15:04:30.537332Z","steps":["trace[428499332] 'read index received' (duration: 29.093718ms)","trace[428499332] 'applied index is now lower than readState.Index' (duration: 84.396784ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:04:30.537805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.945738ms","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-09-25T15:04:30.537911Z","caller":"traceutil/trace.go:171","msg":"trace[1042759927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2097; }","duration":"114.084891ms","start":"2026-09-25T15:04:30.423809Z","end":"2026-09-25T15:04:30.537894Z","steps":["trace[1042759927] 'agreement among raft nodes before linearized reading' (duration: 113.817167ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:04:30.803853Z","caller":"traceutil/trace.go:171","msg":"trace[1333983111] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"189.962361ms","start":"2026-09-25T15:04:30.613866Z","end":"2026-09-25T15:04:30.803828Z","steps":["trace[1333983111] 'read index received' (duration: 189.665717ms)","trace[1333983111] 'applied index is now lower than readState.Index' (duration: 295.784µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:04:30.803937Z","caller":"traceutil/trace.go:171","msg":"trace[1716256682] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"262.20445ms","start":"2026-09-25T15:04:30.541681Z","end":"2026-09-25T15:04:30.803886Z","steps":["trace[1716256682] 'process raft request' (duration: 261.927856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:30.804252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.234059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-25T15:04:30.804256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.130755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-25T15:04:30.804293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.085238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:04:30.80431Z","caller":"traceutil/trace.go:171","msg":"trace[1909029777] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2098; }","duration":"120.331661ms","start":"2026-09-25T15:04:30.683963Z","end":"2026-09-25T15:04:30.804295Z","steps":["trace[1909029777] 'agreement among raft nodes before linearized reading' (duration: 120.209739ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:04:30.804333Z","caller":"traceutil/trace.go:171","msg":"trace[454643420] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2098; }","duration":"149.260946ms","start":"2026-09-25T15:04:30.655056Z","end":"2026-09-25T15:04:30.804317Z","steps":["trace[454643420] 'agreement among raft nodes before linearized reading' (duration: 149.084094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:30.804333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.454208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-25T15:04:30.804355Z","caller":"traceutil/trace.go:171","msg":"trace[514046501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2098; }","duration":"141.17458ms","start":"2026-09-25T15:04:30.663163Z","end":"2026-09-25T15:04:30.804338Z","steps":["trace[514046501] 'agreement among raft nodes before linearized reading' (duration: 141.073019ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:04:30.804376Z","caller":"traceutil/trace.go:171","msg":"trace[1922476552] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2098; }","duration":"190.539279ms","start":"2026-09-25T15:04:30.613822Z","end":"2026-09-25T15:04:30.804361Z","steps":["trace[1922476552] 'agreement among raft nodes before linearized reading' (duration: 190.133473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:31.143956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.260281ms","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-09-25T15:04:31.144047Z","caller":"traceutil/trace.go:171","msg":"trace[1300018977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"140.398604ms","start":"2026-09-25T15:04:31.003626Z","end":"2026-09-25T15:04:31.144024Z","steps":["trace[1300018977] 'range keys from in-memory index tree' (duration: 140.015718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:31.144186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.366749ms","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-09-25T15:04:31.14429Z","caller":"traceutil/trace.go:171","msg":"trace[1397806371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"210.486411ms","start":"2026-09-25T15:04:30.933779Z","end":"2026-09-25T15:04:31.144266Z","steps":["trace[1397806371] 'range keys from in-memory index tree' (duration: 210.115906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:04:31.144925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.414959ms","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-09-25T15:04:31.144988Z","caller":"traceutil/trace.go:171","msg":"trace[967710558] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"139.514491ms","start":"2026-09-25T15:04:31.005454Z","end":"2026-09-25T15:04:31.144969Z","steps":["trace[967710558] 'range keys from in-memory index tree' (duration: 138.428565ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:05:27.408805Z","caller":"traceutil/trace.go:171","msg":"trace[17956698] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"124.916443ms","start":"2026-09-25T15:05:27.283843Z","end":"2026-09-25T15:05:27.408759Z","steps":["trace[17956698] 'process raft request' (duration: 50.056013ms)","trace[17956698] 'compare' (duration: 74.613167ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:06:08.624103Z","caller":"traceutil/trace.go:171","msg":"trace[686742620] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"103.317557ms","start":"2026-09-25T15:06:08.520757Z","end":"2026-09-25T15:06:08.624074Z","steps":["trace[686742620] 'process raft request' (duration: 103.196142ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:09.425607Z","caller":"traceutil/trace.go:171","msg":"trace[1477214531] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"106.110275ms","start":"2026-09-25T15:06:09.319467Z","end":"2026-09-25T15:06:09.425577Z","steps":["trace[1477214531] 'process raft request' (duration: 105.945399ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:44.96819Z","caller":"traceutil/trace.go:171","msg":"trace[1661627823] linearizableReadLoop","detail":"{readStateIndex:3028; appliedIndex:3027; }","duration":"170.218245ms","start":"2026-09-25T15:06:44.797941Z","end":"2026-09-25T15:06:44.968159Z","steps":["trace[1661627823] 'read index received' (duration: 72.004821ms)","trace[1661627823] 'applied index is now lower than readState.Index' (duration: 98.211734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:06:44.968641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.026532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-25T15:06:44.968657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.709892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-09-25T15:06:44.968702Z","caller":"traceutil/trace.go:171","msg":"trace[896916029] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2914; }","duration":"165.127896ms","start":"2026-09-25T15:06:44.803557Z","end":"2026-09-25T15:06:44.968684Z","steps":["trace[896916029] 'agreement among raft nodes before linearized reading' (duration: 164.847486ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:44.968747Z","caller":"traceutil/trace.go:171","msg":"trace[129611949] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2914; }","duration":"170.836126ms","start":"2026-09-25T15:06:44.797895Z","end":"2026-09-25T15:06:44.968732Z","steps":["trace[129611949] 'agreement among raft nodes before linearized reading' (duration: 170.406032ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:20.42795Z","caller":"traceutil/trace.go:171","msg":"trace[2025266022] linearizableReadLoop","detail":"{readStateIndex:5255; appliedIndex:5253; }","duration":"148.847436ms","start":"2026-09-25T15:11:20.279074Z","end":"2026-09-25T15:11:20.427921Z","steps":["trace[2025266022] 'read index received' (duration: 148.625832ms)","trace[2025266022] 'applied index is now lower than readState.Index' (duration: 220.573µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:11:20.428276Z","caller":"traceutil/trace.go:171","msg":"trace[880677150] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"159.854483ms","start":"2026-09-25T15:11:20.268405Z","end":"2026-09-25T15:11:20.428259Z","steps":["trace[880677150] 'process raft request' (duration: 159.301254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:20.428562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.873103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-25T15:11:20.428512Z","caller":"traceutil/trace.go:171","msg":"trace[1422039365] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"159.832932ms","start":"2026-09-25T15:11:20.268553Z","end":"2026-09-25T15:11:20.428386Z","steps":["trace[1422039365] 'process raft request' (duration: 159.300953ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:20.428612Z","caller":"traceutil/trace.go:171","msg":"trace[1355621979] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5059; }","duration":"169.953875ms","start":"2026-09-25T15:11:20.258645Z","end":"2026-09-25T15:11:20.428599Z","steps":["trace[1355621979] 'agreement among raft nodes before linearized reading' (duration: 169.791142ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:20.435908Z","caller":"traceutil/trace.go:171","msg":"trace[983989437] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5060; }","duration":"155.444108ms","start":"2026-09-25T15:11:20.280438Z","end":"2026-09-25T15:11:20.435882Z","steps":["trace[983989437] 'process raft request' (duration: 155.308606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:20.436312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.745308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jjm7w-tls\" ","response":"range_response_count:1 size:2517"} {"level":"info","ts":"2026-09-25T15:11:20.436367Z","caller":"traceutil/trace.go:171","msg":"trace[1843121427] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jjm7w-tls; range_end:; response_count:1; response_revision:5060; }","duration":"150.846779ms","start":"2026-09-25T15:11:20.285505Z","end":"2026-09-25T15:11:20.436352Z","steps":["trace[1843121427] 'agreement among raft nodes before linearized reading' (duration: 150.742437ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:21.428345Z","caller":"traceutil/trace.go:171","msg":"trace[919113315] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"165.69023ms","start":"2026-09-25T15:11:21.262635Z","end":"2026-09-25T15:11:21.428325Z","steps":["trace[919113315] 'read index received' (duration: 165.488166ms)","trace[919113315] 'applied index is now lower than readState.Index' (duration: 201.284µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:11:21.42852Z","caller":"traceutil/trace.go:171","msg":"trace[691374289] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"174.627142ms","start":"2026-09-25T15:11:21.253841Z","end":"2026-09-25T15:11:21.428468Z","steps":["trace[691374289] 'process raft request' (duration: 174.327037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:21.428725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.065326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-25T15:11:21.42969Z","caller":"traceutil/trace.go:171","msg":"trace[926064822] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5069; }","duration":"167.059152ms","start":"2026-09-25T15:11:21.262611Z","end":"2026-09-25T15:11:21.42967Z","steps":["trace[926064822] 'agreement among raft nodes before linearized reading' (duration: 165.879173ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:25.987514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2817} {"level":"info","ts":"2026-09-25T15:11:26.065303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2817,"took":"76.398754ms","hash":4033715036,"current-db-size-bytes":51122176,"current-db-size":"51 MB","current-db-size-in-use-bytes":27160576,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-25T15:11:26.065373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4033715036,"revision":2817,"compact-revision":-1} {"level":"warn","ts":"2026-09-25T15:11:27.70679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.526998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-25T15:11:27.706868Z","caller":"traceutil/trace.go:171","msg":"trace[347520161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5127; }","duration":"127.65184ms","start":"2026-09-25T15:11:27.579198Z","end":"2026-09-25T15:11:27.70685Z","steps":["trace[347520161] 'range keys from in-memory index tree' (duration: 127.313814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:27.707293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.531091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-25T15:11:27.707372Z","caller":"traceutil/trace.go:171","msg":"trace[607111046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5127; }","duration":"110.665063ms","start":"2026-09-25T15:11:27.59669Z","end":"2026-09-25T15:11:27.707355Z","steps":["trace[607111046] 'range keys from in-memory index tree' (duration: 110.317257ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:57.36888Z","caller":"traceutil/trace.go:171","msg":"trace[1298135342] linearizableReadLoop","detail":"{readStateIndex:5640; appliedIndex:5639; }","duration":"123.891344ms","start":"2026-09-25T15:11:57.244971Z","end":"2026-09-25T15:11:57.368862Z","steps":["trace[1298135342] 'read index received' (duration: 119.014264ms)","trace[1298135342] 'applied index is now lower than readState.Index' (duration: 4.8763ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:11:57.369105Z","caller":"traceutil/trace.go:171","msg":"trace[1955114431] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"127.988602ms","start":"2026-09-25T15:11:57.241045Z","end":"2026-09-25T15:11:57.369033Z","steps":["trace[1955114431] 'process raft request' (duration: 123.04243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:57.369317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.156779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:11:57.369782Z","caller":"traceutil/trace.go:171","msg":"trace[1791512363] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5432; }","duration":"124.83768ms","start":"2026-09-25T15:11:57.24493Z","end":"2026-09-25T15:11:57.369767Z","steps":["trace[1791512363] 'agreement among raft nodes before linearized reading' (duration: 124.162939ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:57.636046Z","caller":"traceutil/trace.go:171","msg":"trace[1112326756] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"199.058595ms","start":"2026-09-25T15:11:57.436968Z","end":"2026-09-25T15:11:57.636026Z","steps":["trace[1112326756] 'read index received' (duration: 103.667141ms)","trace[1112326756] 'applied index is now lower than readState.Index' (duration: 95.390544ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:11:57.636153Z","caller":"traceutil/trace.go:171","msg":"trace[1589376724] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"257.773034ms","start":"2026-09-25T15:11:57.378368Z","end":"2026-09-25T15:11:57.636141Z","steps":["trace[1589376724] 'process raft request' (duration: 162.271238ms)","trace[1589376724] 'compare' (duration: 95.128179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:11:57.636442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.459931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c1c16331-108a-4e9d-91aa-6f34879fd2ce\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-09-25T15:11:57.636516Z","caller":"traceutil/trace.go:171","msg":"trace[1721248875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c1c16331-108a-4e9d-91aa-6f34879fd2ce; range_end:; response_count:1; response_revision:5433; }","duration":"199.582113ms","start":"2026-09-25T15:11:57.436916Z","end":"2026-09-25T15:11:57.636498Z","steps":["trace[1721248875] 'agreement among raft nodes before linearized reading' (duration: 199.460021ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:57.866702Z","caller":"traceutil/trace.go:171","msg":"trace[1622396853] linearizableReadLoop","detail":"{readStateIndex:5643; appliedIndex:5642; }","duration":"158.352013ms","start":"2026-09-25T15:11:57.708331Z","end":"2026-09-25T15:11:57.866683Z","steps":["trace[1622396853] 'read index received' (duration: 149.627099ms)","trace[1622396853] 'applied index is now lower than readState.Index' (duration: 8.724134ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:11:57.867057Z","caller":"traceutil/trace.go:171","msg":"trace[1352522537] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"221.377563ms","start":"2026-09-25T15:11:57.645665Z","end":"2026-09-25T15:11:57.867042Z","steps":["trace[1352522537] 'process raft request' (duration: 212.307833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:11:57.867305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.639651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:11:57.867463Z","caller":"traceutil/trace.go:171","msg":"trace[518099959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5435; }","duration":"203.843943ms","start":"2026-09-25T15:11:57.663598Z","end":"2026-09-25T15:11:57.867442Z","steps":["trace[518099959] 'agreement among raft nodes before linearized reading' (duration: 203.496138ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:11:57.870831Z","caller":"traceutil/trace.go:171","msg":"trace[723859734] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"157.674482ms","start":"2026-09-25T15:11:57.713136Z","end":"2026-09-25T15:11:57.870811Z","steps":["trace[723859734] 'process raft request' (duration: 157.484319ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:12:08.497074Z","caller":"traceutil/trace.go:171","msg":"trace[1574764590] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"104.171926ms","start":"2026-09-25T15:12:08.39288Z","end":"2026-09-25T15:12:08.497052Z","steps":["trace[1574764590] 'process raft request' (duration: 103.904291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:15:46.585887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.631011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-25T15:15:46.585964Z","caller":"traceutil/trace.go:171","msg":"trace[1677111240] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7087; }","duration":"102.767227ms","start":"2026-09-25T15:15:46.483181Z","end":"2026-09-25T15:15:46.585948Z","steps":["trace[1677111240] 'count revisions from in-memory index tree' (duration: 102.524558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:15:46.585987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.741393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-25T15:15:46.586142Z","caller":"traceutil/trace.go:171","msg":"trace[1826270994] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7087; }","duration":"276.9278ms","start":"2026-09-25T15:15:46.309186Z","end":"2026-09-25T15:15:46.586114Z","steps":["trace[1826270994] 'range keys from in-memory index tree' (duration: 276.573907ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:15:58.325963Z","caller":"traceutil/trace.go:171","msg":"trace[2142909999] transaction","detail":"{read_only:false; response_revision:7199; number_of_response:1; }","duration":"193.079304ms","start":"2026-09-25T15:15:58.132857Z","end":"2026-09-25T15:15:58.325936Z","steps":["trace[2142909999] 'process raft request' (duration: 192.934788ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:15:58.578972Z","caller":"traceutil/trace.go:171","msg":"trace[888114176] transaction","detail":"{read_only:false; response_revision:7200; number_of_response:1; }","duration":"267.006788ms","start":"2026-09-25T15:15:58.311946Z","end":"2026-09-25T15:15:58.578953Z","steps":["trace[888114176] 'process raft request' (duration: 266.888104ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:15:58.698344Z","caller":"traceutil/trace.go:171","msg":"trace[717058970] transaction","detail":"{read_only:false; response_revision:7201; number_of_response:1; }","duration":"340.148024ms","start":"2026-09-25T15:15:58.358168Z","end":"2026-09-25T15:15:58.698316Z","steps":["trace[717058970] 'process raft request' (duration: 330.955048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:15:58.698557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:15:58.358144Z","time spent":"340.270989ms","remote":"127.0.0.1:55902","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-09-25T15:16:05.339985Z","caller":"traceutil/trace.go:171","msg":"trace[1795207605] linearizableReadLoop","detail":"{readStateIndex:7503; appliedIndex:7502; }","duration":"140.060144ms","start":"2026-09-25T15:16:05.199885Z","end":"2026-09-25T15:16:05.339963Z","steps":["trace[1795207605] 'read index received' (duration: 139.919899ms)","trace[1795207605] 'applied index is now lower than readState.Index' (duration: 139.315µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:16:05.340102Z","caller":"traceutil/trace.go:171","msg":"trace[1705561584] transaction","detail":"{read_only:false; response_revision:7233; number_of_response:1; }","duration":"186.204889ms","start":"2026-09-25T15:16:05.153809Z","end":"2026-09-25T15:16:05.340014Z","steps":["trace[1705561584] 'process raft request' (duration: 186.019013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:16:05.340177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.272191ms","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-09-25T15:16:05.340207Z","caller":"traceutil/trace.go:171","msg":"trace[1959135789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7233; }","duration":"140.345904ms","start":"2026-09-25T15:16:05.199851Z","end":"2026-09-25T15:16:05.340197Z","steps":["trace[1959135789] 'agreement among raft nodes before linearized reading' (duration: 140.203419ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:16:05.383028Z","caller":"traceutil/trace.go:171","msg":"trace[1956669130] transaction","detail":"{read_only:false; response_revision:7234; number_of_response:1; }","duration":"102.733318ms","start":"2026-09-25T15:16:05.280269Z","end":"2026-09-25T15:16:05.383002Z","steps":["trace[1956669130] 'process raft request' (duration: 102.536081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:16:05.383067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.574852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-25T15:16:05.383105Z","caller":"traceutil/trace.go:171","msg":"trace[27133632] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7234; }","duration":"140.681476ms","start":"2026-09-25T15:16:05.242412Z","end":"2026-09-25T15:16:05.383094Z","steps":["trace[27133632] 'agreement among raft nodes before linearized reading' (duration: 140.52014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:16:05.383273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.880542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-25T15:16:05.38331Z","caller":"traceutil/trace.go:171","msg":"trace[706024397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7234; }","duration":"100.943404ms","start":"2026-09-25T15:16:05.28236Z","end":"2026-09-25T15:16:05.383303Z","steps":["trace[706024397] 'agreement among raft nodes before linearized reading' (duration: 100.880242ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:16:25.997542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5105} {"level":"info","ts":"2026-09-25T15:16:26.070909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5105,"took":"72.238119ms","hash":1482093238,"current-db-size-bytes":51122176,"current-db-size":"51 MB","current-db-size-in-use-bytes":19333120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-25T15:16:26.070975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1482093238,"revision":5105,"compact-revision":2817}