{"level":"warn","ts":"2026-08-11T07:18:52.150314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T07:18:52.150411Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-08-11T07:18:52.150476Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T07:18:52.150485Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-11T07:18:52.150504Z","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-08-11T07:18:52.151117Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-08-11T07:18:52.151183Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-08-11T07:18:52.155051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.674033ms"} {"level":"info","ts":"2026-08-11T07:18:52.161721Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-08-11T07:18:52.161841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T07:18:52.161901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-08-11T07:18:52.161937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T07:18:52.161972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-08-11T07:18:52.162052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-08-11T07:18:52.16821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T07:18:52.16979Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T07:18:52.170843Z","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-08-11T07:18:52.17376Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T07:18:52.174206Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T07:18:52.17453Z","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-08-11T07:18:52.174608Z","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-08-11T07:18:52.174623Z","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-08-11T07:18:52.176296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-08-11T07:18:52.176451Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-11T07:18:52.177965Z","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-08-11T07:18:52.178023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-11T07:18:52.178044Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-11T07:18:52.178156Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T07:18:52.17819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T07:18:52.962738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T07:18:52.962827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T07:18:52.962887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-08-11T07:18:52.962909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-08-11T07:18:52.962921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-11T07:18:52.962936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-08-11T07:18:52.962986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-11T07:18:52.964055Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T07:18:52.964071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:18:52.964089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T07:18:52.964292Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:18:52.964346Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T07:18:52.964407Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T07:18:52.965385Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:18:52.965522Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:18:52.965559Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T07:18:52.966182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-08-11T07:18:52.969945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T07:19:02.74367Z","caller":"traceutil/trace.go:171","msg":"trace[2067601816] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"313.879402ms","start":"2026-08-11T07:19:02.429765Z","end":"2026-08-11T07:19:02.743644Z","steps":["trace[2067601816] 'read index received' (duration: 313.69456ms)","trace[2067601816] 'applied index is now lower than readState.Index' (duration: 183.862µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:19:02.743787Z","caller":"traceutil/trace.go:171","msg":"trace[553922848] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"365.420978ms","start":"2026-08-11T07:19:02.378333Z","end":"2026-08-11T07:19:02.743754Z","steps":["trace[553922848] 'process raft request' (duration: 365.151565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:02.744047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.245755ms","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-08-11T07:19:02.744208Z","caller":"traceutil/trace.go:171","msg":"trace[918194627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"314.466746ms","start":"2026-08-11T07:19:02.429722Z","end":"2026-08-11T07:19:02.744188Z","steps":["trace[918194627] 'agreement among raft nodes before linearized reading' (duration: 314.174704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:02.744325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.4297Z","time spent":"314.600987ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:19:02.744591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.378313Z","time spent":"365.833471ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:19:03.284526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.621074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:19:03.284658Z","caller":"traceutil/trace.go:171","msg":"trace[974098651] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"535.955906ms","start":"2026-08-11T07:19:02.748682Z","end":"2026-08-11T07:19:03.284638Z","steps":["trace[974098651] 'process raft request' (duration: 373.915809ms)","trace[974098651] 'compare' (duration: 161.513443ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:19:03.284736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.748658Z","time spent":"536.043067ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:19:03.289635Z","caller":"traceutil/trace.go:171","msg":"trace[1670065548] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:385; }","duration":"185.114366ms","start":"2026-08-11T07:19:03.104505Z","end":"2026-08-11T07:19:03.289619Z","steps":["trace[1670065548] 'read index received' (duration: 18.190179ms)","trace[1670065548] 'applied index is now lower than readState.Index' (duration: 166.923107ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:19:03.28978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.265357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T07:19:03.289813Z","caller":"traceutil/trace.go:171","msg":"trace[1047346996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"185.342977ms","start":"2026-08-11T07:19:03.104461Z","end":"2026-08-11T07:19:03.289804Z","steps":["trace[1047346996] 'agreement among raft nodes before linearized reading' (duration: 185.218966ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:19:03.290098Z","caller":"traceutil/trace.go:171","msg":"trace[1235634110] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"539.163501ms","start":"2026-08-11T07:19:02.750925Z","end":"2026-08-11T07:19:03.290088Z","steps":["trace[1235634110] 'process raft request' (duration: 538.501806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:03.290151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.750902Z","time spent":"539.220962ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:19:03.29024Z","caller":"traceutil/trace.go:171","msg":"trace[639045335] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"539.044871ms","start":"2026-08-11T07:19:02.751189Z","end":"2026-08-11T07:19:03.290234Z","steps":["trace[639045335] 'process raft request' (duration: 538.304265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:03.290268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.751181Z","time spent":"539.071611ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:19:03.290344Z","caller":"traceutil/trace.go:171","msg":"trace[448042327] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"539.096482ms","start":"2026-08-11T07:19:02.751242Z","end":"2026-08-11T07:19:03.290338Z","steps":["trace[448042327] 'process raft request' (duration: 538.289175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:03.29037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.751236Z","time spent":"539.121852ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T07:19:03.290444Z","caller":"traceutil/trace.go:171","msg":"trace[1674896263] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"524.571551ms","start":"2026-08-11T07:19:02.765867Z","end":"2026-08-11T07:19:03.290439Z","steps":["trace[1674896263] 'process raft request' (duration: 523.696894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:03.290483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:02.765861Z","time spent":"524.604032ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:19:04.793529Z","caller":"traceutil/trace.go:171","msg":"trace[1400123749] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"167.035677ms","start":"2026-08-11T07:19:04.626468Z","end":"2026-08-11T07:19:04.793504Z","steps":["trace[1400123749] 'read index received' (duration: 80.406294ms)","trace[1400123749] 'applied index is now lower than readState.Index' (duration: 86.628243ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:19:04.793598Z","caller":"traceutil/trace.go:171","msg":"trace[111772409] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"201.273275ms","start":"2026-08-11T07:19:04.592297Z","end":"2026-08-11T07:19:04.793571Z","steps":["trace[111772409] 'process raft request' (duration: 114.628471ms)","trace[111772409] 'compare' (duration: 86.451272ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:19:04.793736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.207059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:19:04.793804Z","caller":"traceutil/trace.go:171","msg":"trace[127665332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"167.361219ms","start":"2026-08-11T07:19:04.626406Z","end":"2026-08-11T07:19:04.793767Z","steps":["trace[127665332] 'agreement among raft nodes before linearized reading' (duration: 167.213418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:31.362404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.618973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-11T07:19:38.85256Z","caller":"traceutil/trace.go:171","msg":"trace[1575798819] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"161.269179ms","start":"2026-08-11T07:19:38.691269Z","end":"2026-08-11T07:19:38.852538Z","steps":["trace[1575798819] 'process raft request' (duration: 161.121083ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:19:39.260477Z","caller":"traceutil/trace.go:171","msg":"trace[1135749959] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"371.811695ms","start":"2026-08-11T07:19:38.888638Z","end":"2026-08-11T07:19:39.26045Z","steps":["trace[1135749959] 'process raft request' (duration: 371.264012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:39.260642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:38.888605Z","time spent":"371.94946ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:19:39.96814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.660272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:19:39.968321Z","caller":"traceutil/trace.go:171","msg":"trace[963300718] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"455.834963ms","start":"2026-08-11T07:19:39.512468Z","end":"2026-08-11T07:19:39.968303Z","steps":["trace[963300718] 'read index received' (duration: 163.864097ms)","trace[963300718] 'applied index is now lower than readState.Index' (duration: 291.969746ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:19:39.96849Z","caller":"traceutil/trace.go:171","msg":"trace[1321397863] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"645.074637ms","start":"2026-08-11T07:19:39.323403Z","end":"2026-08-11T07:19:39.968477Z","steps":["trace[1321397863] 'process raft request' (duration: 352.981156ms)","trace[1321397863] 'compare' (duration: 291.526827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:19:39.968645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:39.32337Z","time spent":"645.188603ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:19:39.969063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.459575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:19:39.969138Z","caller":"traceutil/trace.go:171","msg":"trace[1427476640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"442.58005ms","start":"2026-08-11T07:19:39.526517Z","end":"2026-08-11T07:19:39.969097Z","steps":["trace[1427476640] 'agreement among raft nodes before linearized reading' (duration: 442.421584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:39.969188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:39.526483Z","time spent":"442.697855ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:19:39.969319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.894564ms","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-08-11T07:19:39.969449Z","caller":"traceutil/trace.go:171","msg":"trace[890099088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"427.060421ms","start":"2026-08-11T07:19:39.542361Z","end":"2026-08-11T07:19:39.969422Z","steps":["trace[890099088] 'agreement among raft nodes before linearized reading' (duration: 426.653904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:39.96953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:39.542351Z","time spent":"427.165596ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T07:19:39.969609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.746322ms","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-08-11T07:19:39.969686Z","caller":"traceutil/trace.go:171","msg":"trace[1101207043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"457.256964ms","start":"2026-08-11T07:19:39.512414Z","end":"2026-08-11T07:19:39.96967Z","steps":["trace[1101207043] 'agreement among raft nodes before linearized reading' (duration: 456.584535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:39.969839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:19:39.512392Z","time spent":"457.428071ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T07:19:52.881703Z","caller":"traceutil/trace.go:171","msg":"trace[1346382110] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"105.256057ms","start":"2026-08-11T07:19:52.776423Z","end":"2026-08-11T07:19:52.881679Z","steps":["trace[1346382110] 'process raft request' (duration: 105.096151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:19:55.417568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.02498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T07:19:55.417742Z","caller":"traceutil/trace.go:171","msg":"trace[1976307691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"140.250527ms","start":"2026-08-11T07:19:55.277469Z","end":"2026-08-11T07:19:55.41772Z","steps":["trace[1976307691] 'range keys from in-memory index tree' (duration: 139.788111ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:20:23.354326Z","caller":"traceutil/trace.go:171","msg":"trace[2143741403] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"246.211554ms","start":"2026-08-11T07:20:23.108085Z","end":"2026-08-11T07:20:23.354296Z","steps":["trace[2143741403] 'process raft request' (duration: 246.027489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.097232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.713201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:21:11.097542Z","caller":"traceutil/trace.go:171","msg":"trace[814956798] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"622.803996ms","start":"2026-08-11T07:21:10.474721Z","end":"2026-08-11T07:21:11.097525Z","steps":["trace[814956798] 'process raft request' (duration: 274.714668ms)","trace[814956798] 'compare' (duration: 347.585539ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:11.097629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.4747Z","time spent":"622.893458ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:21:11.097923Z","caller":"traceutil/trace.go:171","msg":"trace[1674879940] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"589.253597ms","start":"2026-08-11T07:21:10.508072Z","end":"2026-08-11T07:21:11.097325Z","steps":["trace[1674879940] 'read index received' (duration: 38.335196ms)","trace[1674879940] 'applied index is now lower than readState.Index' (duration: 550.917361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:11.097728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.618773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:21:11.098342Z","caller":"traceutil/trace.go:171","msg":"trace[2039106779] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1879; }","duration":"590.259905ms","start":"2026-08-11T07:21:10.508033Z","end":"2026-08-11T07:21:11.098293Z","steps":["trace[2039106779] 'agreement among raft nodes before linearized reading' (duration: 589.581883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.098431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.508019Z","time spent":"590.366987ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:21:11.214014Z","caller":"traceutil/trace.go:171","msg":"trace[1842981064] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"338.879487ms","start":"2026-08-11T07:21:10.875073Z","end":"2026-08-11T07:21:11.213952Z","steps":["trace[1842981064] 'process raft request' (duration: 338.582712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.214147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.051808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:21:11.214158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.194218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:21:11.214251Z","caller":"traceutil/trace.go:171","msg":"trace[968786871] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1881; }","duration":"181.359392ms","start":"2026-08-11T07:21:11.032874Z","end":"2026-08-11T07:21:11.214234Z","steps":["trace[968786871] 'agreement among raft nodes before linearized reading' (duration: 181.168058ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:11.214195Z","caller":"traceutil/trace.go:171","msg":"trace[476023989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"687.158521ms","start":"2026-08-11T07:21:10.527026Z","end":"2026-08-11T07:21:11.214185Z","steps":["trace[476023989] 'agreement among raft nodes before linearized reading' (duration: 687.067489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.214408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.527007Z","time spent":"687.388404ms","remote":"127.0.0.1:44802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:21:11.214195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.875036Z","time spent":"339.073521ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:11.214423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.804036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T07:21:11.214043Z","caller":"traceutil/trace.go:171","msg":"trace[150327072] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1948; }","duration":"116.063644ms","start":"2026-08-11T07:21:11.097955Z","end":"2026-08-11T07:21:11.214018Z","steps":["trace[150327072] 'read index received' (duration: 35.972879ms)","trace[150327072] 'applied index is now lower than readState.Index' (duration: 80.089155ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:11.214553Z","caller":"traceutil/trace.go:171","msg":"trace[1170712571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"301.01055ms","start":"2026-08-11T07:21:10.913517Z","end":"2026-08-11T07:21:11.214527Z","steps":["trace[1170712571] 'agreement among raft nodes before linearized reading' (duration: 300.582752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.214756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.913494Z","time spent":"301.205633ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:21:11.216926Z","caller":"traceutil/trace.go:171","msg":"trace[846897706] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"311.807595ms","start":"2026-08-11T07:21:10.905091Z","end":"2026-08-11T07:21:11.216898Z","steps":["trace[846897706] 'process raft request' (duration: 308.697249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:11.218866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:10.905076Z","time spent":"311.911497ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:11.504035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.16837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:21:11.504318Z","caller":"traceutil/trace.go:171","msg":"trace[559747494] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"282.480025ms","start":"2026-08-11T07:21:11.22181Z","end":"2026-08-11T07:21:11.50429Z","steps":["trace[559747494] 'process raft request' (duration: 96.962079ms)","trace[559747494] 'compare' (duration: 184.757652ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:11.75826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.032323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:21:11.758365Z","caller":"traceutil/trace.go:171","msg":"trace[1142410314] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"149.381804ms","start":"2026-08-11T07:21:11.608963Z","end":"2026-08-11T07:21:11.758345Z","steps":["trace[1142410314] 'process raft request' (duration: 43.167859ms)","trace[1142410314] 'compare' (duration: 105.854279ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:22.728358Z","caller":"traceutil/trace.go:171","msg":"trace[607512893] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"146.539979ms","start":"2026-08-11T07:21:22.581766Z","end":"2026-08-11T07:21:22.728306Z","steps":["trace[607512893] 'process raft request' (duration: 104.247386ms)","trace[607512893] 'compare' (duration: 42.019858ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:27.023222Z","caller":"traceutil/trace.go:171","msg":"trace[502173106] linearizableReadLoop","detail":"{readStateIndex:2075; appliedIndex:2074; }","duration":"275.083876ms","start":"2026-08-11T07:21:26.748115Z","end":"2026-08-11T07:21:27.023199Z","steps":["trace[502173106] 'read index received' (duration: 274.894252ms)","trace[502173106] 'applied index is now lower than readState.Index' (duration: 188.524µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:27.023402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.266228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:21:27.023444Z","caller":"traceutil/trace.go:171","msg":"trace[1447894237] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2002; }","duration":"275.36099ms","start":"2026-08-11T07:21:26.748071Z","end":"2026-08-11T07:21:27.023432Z","steps":["trace[1447894237] 'agreement among raft nodes before linearized reading' (duration: 275.236308ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:27.02385Z","caller":"traceutil/trace.go:171","msg":"trace[1400093720] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"284.306218ms","start":"2026-08-11T07:21:26.739517Z","end":"2026-08-11T07:21:27.023823Z","steps":["trace[1400093720] 'process raft request' (duration: 283.538005ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:49.683303Z","caller":"traceutil/trace.go:171","msg":"trace[1059445755] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2217; }","duration":"172.145654ms","start":"2026-08-11T07:21:49.511137Z","end":"2026-08-11T07:21:49.683283Z","steps":["trace[1059445755] 'read index received' (duration: 171.990621ms)","trace[1059445755] 'applied index is now lower than readState.Index' (duration: 153.952µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:49.683432Z","caller":"traceutil/trace.go:171","msg":"trace[1966761458] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"202.228534ms","start":"2026-08-11T07:21:49.481174Z","end":"2026-08-11T07:21:49.683403Z","steps":["trace[1966761458] 'process raft request' (duration: 201.9464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:49.68354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.374507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T07:21:49.683576Z","caller":"traceutil/trace.go:171","msg":"trace[2057135231] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"172.475519ms","start":"2026-08-11T07:21:49.511091Z","end":"2026-08-11T07:21:49.683567Z","steps":["trace[2057135231] 'agreement among raft nodes before linearized reading' (duration: 172.308956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:49.683803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.643576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:21:49.683938Z","caller":"traceutil/trace.go:171","msg":"trace[1498556134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"158.849599ms","start":"2026-08-11T07:21:49.52507Z","end":"2026-08-11T07:21:49.683919Z","steps":["trace[1498556134] 'agreement among raft nodes before linearized reading' (duration: 158.474553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:50.140323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.933553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:21:50.140613Z","caller":"traceutil/trace.go:171","msg":"trace[75543263] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"241.3554ms","start":"2026-08-11T07:21:49.899235Z","end":"2026-08-11T07:21:50.140591Z","steps":["trace[75543263] 'process raft request' (duration: 241.224638ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:50.140643Z","caller":"traceutil/trace.go:171","msg":"trace[1161750937] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"242.527597ms","start":"2026-08-11T07:21:49.898065Z","end":"2026-08-11T07:21:50.140593Z","steps":["trace[1161750937] 'process raft request' (duration: 29.225568ms)","trace[1161750937] 'compare' (duration: 212.765781ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:51.069174Z","caller":"traceutil/trace.go:171","msg":"trace[1558131072] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"377.615758ms","start":"2026-08-11T07:21:50.69154Z","end":"2026-08-11T07:21:51.069155Z","steps":["trace[1558131072] 'process raft request' (duration: 377.400535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.069367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:50.691517Z","time spent":"377.738959ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:51.069468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.540607ms","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-08-11T07:21:51.069554Z","caller":"traceutil/trace.go:171","msg":"trace[1352241245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2145; }","duration":"377.642979ms","start":"2026-08-11T07:21:50.691893Z","end":"2026-08-11T07:21:51.069536Z","steps":["trace[1352241245] 'agreement among raft nodes before linearized reading' (duration: 377.391995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.069606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:50.691855Z","time spent":"377.741399ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:21:51.069934Z","caller":"traceutil/trace.go:171","msg":"trace[196579686] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"377.189432ms","start":"2026-08-11T07:21:50.691898Z","end":"2026-08-11T07:21:51.069088Z","steps":["trace[196579686] 'read index received' (duration: 376.941948ms)","trace[196579686] 'applied index is now lower than readState.Index' (duration: 246.704µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:51.375471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.92856ms","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-08-11T07:21:51.375629Z","caller":"traceutil/trace.go:171","msg":"trace[820979018] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2145; }","duration":"192.110553ms","start":"2026-08-11T07:21:51.183494Z","end":"2026-08-11T07:21:51.375604Z","steps":["trace[820979018] 'range keys from in-memory index tree' (duration: 191.90505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.376825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.948046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:21:51.377036Z","caller":"traceutil/trace.go:171","msg":"trace[1880581175] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"552.107985ms","start":"2026-08-11T07:21:50.824899Z","end":"2026-08-11T07:21:51.377007Z","steps":["trace[1880581175] 'process raft request' (duration: 435.161865ms)","trace[1880581175] 'compare' (duration: 115.850564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:51.377266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:50.824873Z","time spent":"552.318789ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:51.537423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.493382ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-11T07:21:51.537606Z","caller":"traceutil/trace.go:171","msg":"trace[2126190944] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"467.434324ms","start":"2026-08-11T07:21:51.070066Z","end":"2026-08-11T07:21:51.537501Z","steps":["trace[2126190944] 'read index received' (duration: 190.005032ms)","trace[2126190944] 'applied index is now lower than readState.Index' (duration: 277.427942ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:21:51.537862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.843352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-08-11T07:21:51.537919Z","caller":"traceutil/trace.go:171","msg":"trace[528277802] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2146; }","duration":"773.926383ms","start":"2026-08-11T07:21:50.763968Z","end":"2026-08-11T07:21:51.537894Z","steps":["trace[528277802] 'agreement among raft nodes before linearized reading' (duration: 773.757361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.537987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:50.763952Z","time spent":"774.024184ms","remote":"127.0.0.1:45044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-08-11T07:21:51.537907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.64324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T07:21:51.53817Z","caller":"traceutil/trace.go:171","msg":"trace[309685332] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"845.922724ms","start":"2026-08-11T07:21:50.692204Z","end":"2026-08-11T07:21:51.538127Z","steps":["trace[309685332] 'agreement among raft nodes before linearized reading' (duration: 845.538918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.538287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:50.692187Z","time spent":"846.056586ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-11T07:21:51.545541Z","caller":"traceutil/trace.go:171","msg":"trace[600719677] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"406.073562ms","start":"2026-08-11T07:21:51.139451Z","end":"2026-08-11T07:21:51.545525Z","steps":["trace[600719677] 'process raft request' (duration: 406.025722ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:51.545599Z","caller":"traceutil/trace.go:171","msg":"trace[1089693181] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"471.124687ms","start":"2026-08-11T07:21:51.074454Z","end":"2026-08-11T07:21:51.545579Z","steps":["trace[1089693181] 'process raft request' (duration: 470.906524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.54575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:51.139425Z","time spent":"406.167753ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:51.54605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:21:51.074431Z","time spent":"471.304739ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:21:51.5546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.711365ms","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-08-11T07:21:51.5547Z","caller":"traceutil/trace.go:171","msg":"trace[1437650113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2149; }","duration":"217.879197ms","start":"2026-08-11T07:21:51.336787Z","end":"2026-08-11T07:21:51.554666Z","steps":["trace[1437650113] 'agreement among raft nodes before linearized reading' (duration: 217.615933ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:21:51.832461Z","caller":"traceutil/trace.go:171","msg":"trace[1889621431] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"227.665609ms","start":"2026-08-11T07:21:51.604769Z","end":"2026-08-11T07:21:51.832435Z","steps":["trace[1889621431] 'read index received' (duration: 223.862174ms)","trace[1889621431] 'applied index is now lower than readState.Index' (duration: 3.802165ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:21:51.832516Z","caller":"traceutil/trace.go:171","msg":"trace[258088281] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"269.955513ms","start":"2026-08-11T07:21:51.562539Z","end":"2026-08-11T07:21:51.832495Z","steps":["trace[258088281] 'process raft request' (duration: 266.122677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.832633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.848671ms","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-08-11T07:21:51.832673Z","caller":"traceutil/trace.go:171","msg":"trace[286192348] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2150; }","duration":"227.963753ms","start":"2026-08-11T07:21:51.604699Z","end":"2026-08-11T07:21:51.832663Z","steps":["trace[286192348] 'agreement among raft nodes before linearized reading' (duration: 227.872732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:21:51.832818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.021158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T07:21:51.832922Z","caller":"traceutil/trace.go:171","msg":"trace[840133158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2150; }","duration":"145.18256ms","start":"2026-08-11T07:21:51.687722Z","end":"2026-08-11T07:21:51.832905Z","steps":["trace[840133158] 'agreement among raft nodes before linearized reading' (duration: 144.880785ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:22:41.463586Z","caller":"traceutil/trace.go:171","msg":"trace[474542154] linearizableReadLoop","detail":"{readStateIndex:2612; appliedIndex:2611; }","duration":"253.566094ms","start":"2026-08-11T07:22:41.209989Z","end":"2026-08-11T07:22:41.463556Z","steps":["trace[474542154] 'read index received' (duration: 216.562186ms)","trace[474542154] 'applied index is now lower than readState.Index' (duration: 37.002268ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:22:41.463754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.743756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:22:41.463851Z","caller":"traceutil/trace.go:171","msg":"trace[1916948126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2522; }","duration":"253.891567ms","start":"2026-08-11T07:22:41.209936Z","end":"2026-08-11T07:22:41.463828Z","steps":["trace[1916948126] 'agreement among raft nodes before linearized reading' (duration: 253.726855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:22:41.463903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.49723ms","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-08-11T07:22:41.463949Z","caller":"traceutil/trace.go:171","msg":"trace[1388401140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2522; }","duration":"167.585521ms","start":"2026-08-11T07:22:41.296353Z","end":"2026-08-11T07:22:41.463938Z","steps":["trace[1388401140] 'agreement among raft nodes before linearized reading' (duration: 167.388709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:14.403083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.296095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:23:14.403159Z","caller":"traceutil/trace.go:171","msg":"trace[1392202929] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"179.143802ms","start":"2026-08-11T07:23:14.224003Z","end":"2026-08-11T07:23:14.403147Z","steps":["trace[1392202929] 'read index received' (duration: 49.781µs)","trace[1392202929] 'applied index is now lower than readState.Index' (duration: 179.093231ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:23:14.403218Z","caller":"traceutil/trace.go:171","msg":"trace[169401240] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"430.659186ms","start":"2026-08-11T07:23:13.972551Z","end":"2026-08-11T07:23:14.40321Z","steps":["trace[169401240] 'process raft request' (duration: 121.173769ms)","trace[169401240] 'compare' (duration: 309.012811ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:23:14.403256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:23:13.972523Z","time spent":"430.715256ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:23:14.403392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.394475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18caaf533b77a28b\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-11T07:23:14.403409Z","caller":"traceutil/trace.go:171","msg":"trace[361080994] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18caaf533b77a28b; range_end:; response_count:1; response_revision:2704; }","duration":"179.442085ms","start":"2026-08-11T07:23:14.223963Z","end":"2026-08-11T07:23:14.403405Z","steps":["trace[361080994] 'agreement among raft nodes before linearized reading' (duration: 179.361434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:14.403667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.230666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T07:23:14.403783Z","caller":"traceutil/trace.go:171","msg":"trace[1258285550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2704; }","duration":"150.393868ms","start":"2026-08-11T07:23:14.253369Z","end":"2026-08-11T07:23:14.403763Z","steps":["trace[1258285550] 'agreement among raft nodes before linearized reading' (duration: 150.041064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:14.403936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.932566ms","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-08-11T07:23:14.404182Z","caller":"traceutil/trace.go:171","msg":"trace[1632297661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2704; }","duration":"140.307441ms","start":"2026-08-11T07:23:14.263758Z","end":"2026-08-11T07:23:14.404065Z","steps":["trace[1632297661] 'agreement among raft nodes before linearized reading' (duration: 139.674393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:15.102069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.10834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:23:15.102327Z","caller":"traceutil/trace.go:171","msg":"trace[1075808993] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"177.834844ms","start":"2026-08-11T07:23:14.924474Z","end":"2026-08-11T07:23:15.102308Z","steps":["trace[1075808993] 'process raft request' (duration: 177.789974ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:23:15.102542Z","caller":"traceutil/trace.go:171","msg":"trace[1117099172] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"678.732856ms","start":"2026-08-11T07:23:14.423797Z","end":"2026-08-11T07:23:15.10253Z","steps":["trace[1117099172] 'process raft request' (duration: 264.056029ms)","trace[1117099172] 'compare' (duration: 413.919058ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:23:15.10261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:23:14.423774Z","time spent":"678.798586ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:23:15.10292Z","caller":"traceutil/trace.go:171","msg":"trace[444339272] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"679.018529ms","start":"2026-08-11T07:23:14.423889Z","end":"2026-08-11T07:23:15.102907Z","steps":["trace[444339272] 'process raft request' (duration: 678.27655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:15.102996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:23:14.423882Z","time spent":"679.08189ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:23:15.103006Z","caller":"traceutil/trace.go:171","msg":"trace[467804550] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2804; }","duration":"578.41297ms","start":"2026-08-11T07:23:14.524561Z","end":"2026-08-11T07:23:15.102974Z","steps":["trace[467804550] 'read index received' (duration: 163.213977ms)","trace[467804550] 'applied index is now lower than readState.Index' (duration: 415.196303ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:23:15.10325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.742824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:23:15.103323Z","caller":"traceutil/trace.go:171","msg":"trace[666523406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2710; }","duration":"578.857225ms","start":"2026-08-11T07:23:14.524436Z","end":"2026-08-11T07:23:15.103293Z","steps":["trace[666523406] 'agreement among raft nodes before linearized reading' (duration: 578.749623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:15.103376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:23:14.524414Z","time spent":"578.949265ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:23:15.103464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.786859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-11T07:23:15.103557Z","caller":"traceutil/trace.go:171","msg":"trace[749432101] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2710; }","duration":"162.930271ms","start":"2026-08-11T07:23:14.940611Z","end":"2026-08-11T07:23:15.103541Z","steps":["trace[749432101] 'agreement among raft nodes before linearized reading' (duration: 162.633407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:15.103491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.250548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T07:23:15.103695Z","caller":"traceutil/trace.go:171","msg":"trace[1545150762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2710; }","duration":"541.48537ms","start":"2026-08-11T07:23:14.562192Z","end":"2026-08-11T07:23:15.103678Z","steps":["trace[1545150762] 'agreement among raft nodes before linearized reading' (duration: 541.045505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:23:15.103755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:23:14.562174Z","time spent":"541.563932ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T07:24:30.274541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747617010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:24:30.775436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747617010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:24:31.275581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747617010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:24:31.77608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747617010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:24:32.276827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747617010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T07:24:32.366079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.872037832s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-11T07:24:32.741437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.218161183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-11T07:24:32.741902Z","caller":"traceutil/trace.go:171","msg":"trace[1749623693] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.218719283s","start":"2026-08-11T07:24:30.523155Z","end":"2026-08-11T07:24:32.741875Z","steps":["trace[1749623693] 'agreement among raft nodes before linearized reading' (duration: 2.218225725s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.742335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.523134Z","time spent":"2.219178349s","remote":"127.0.0.1:44802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/11 07:24:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-11T07:24:32.783893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600227072s","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-08-11T07:24:32.78404Z","caller":"traceutil/trace.go:171","msg":"trace[52214472] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3100; }","duration":"1.600384528s","start":"2026-08-11T07:24:31.183633Z","end":"2026-08-11T07:24:32.784017Z","steps":["trace[52214472] 'range keys from in-memory index tree' (duration: 1.600208032s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.785773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.334485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:24:32.785849Z","caller":"traceutil/trace.go:171","msg":"trace[868548937] transaction","detail":"{read_only:false; response_revision:3101; number_of_response:1; }","duration":"3.291895556s","start":"2026-08-11T07:24:29.49393Z","end":"2026-08-11T07:24:32.785825Z","steps":["trace[868548937] 'process raft request' (duration: 2.872420356s)","trace[868548937] 'compare' (duration: 418.882439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:24:32.786734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:29.49391Z","time spent":"3.292781588s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:24:32.799151Z","caller":"traceutil/trace.go:171","msg":"trace[1123847721] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"2.690061764s","start":"2026-08-11T07:24:30.109078Z","end":"2026-08-11T07:24:32.79914Z","steps":["trace[1123847721] 'process raft request' (duration: 2.690007532s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:24:32.799152Z","caller":"traceutil/trace.go:171","msg":"trace[2077146821] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"2.690618823s","start":"2026-08-11T07:24:30.108514Z","end":"2026-08-11T07:24:32.799132Z","steps":["trace[2077146821] 'process raft request' (duration: 2.690495879s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.799227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.109067Z","time spent":"2.690124085s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:24:32.799238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.10849Z","time spent":"2.690707227s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:24:32.799372Z","caller":"traceutil/trace.go:171","msg":"trace[237491136] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"2.651619432s","start":"2026-08-11T07:24:30.147731Z","end":"2026-08-11T07:24:32.799351Z","steps":["trace[237491136] 'process raft request' (duration: 2.651380204s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.799438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.147717Z","time spent":"2.651687665s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:24:32.799594Z","caller":"traceutil/trace.go:171","msg":"trace[988456939] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"1.641741773s","start":"2026-08-11T07:24:31.157841Z","end":"2026-08-11T07:24:32.799583Z","steps":["trace[988456939] 'process raft request' (duration: 1.641431592s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.799654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.157813Z","time spent":"1.641810676s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:24:32.807853Z","caller":"traceutil/trace.go:171","msg":"trace[174028933] transaction","detail":"{read_only:false; response_revision:3106; number_of_response:1; }","duration":"1.44137702s","start":"2026-08-11T07:24:31.366451Z","end":"2026-08-11T07:24:32.807828Z","steps":["trace[174028933] 'process raft request' (duration: 1.440957975s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.366426Z","time spent":"1.441500664s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:24:32.808061Z","caller":"traceutil/trace.go:171","msg":"trace[247713209] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"1.432187073s","start":"2026-08-11T07:24:31.375856Z","end":"2026-08-11T07:24:32.808043Z","steps":["trace[247713209] 'process raft request' (duration: 1.431913034s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:24:32.808151Z","caller":"traceutil/trace.go:171","msg":"trace[1594498785] linearizableReadLoop","detail":"{readStateIndex:3222; appliedIndex:3213; }","duration":"3.034345006s","start":"2026-08-11T07:24:29.77378Z","end":"2026-08-11T07:24:32.808125Z","steps":["trace[1594498785] 'read index received' (duration: 2.592541937s)","trace[1594498785] 'applied index is now lower than readState.Index' (duration: 441.801519ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:24:32.808176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.375843Z","time spent":"1.432271796s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:24:32.808304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.034504662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:24:32.808386Z","caller":"traceutil/trace.go:171","msg":"trace[1448098477] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:3108; }","duration":"3.034655937s","start":"2026-08-11T07:24:29.773706Z","end":"2026-08-11T07:24:32.808361Z","steps":["trace[1448098477] 'agreement among raft nodes before linearized reading' (duration: 3.034496652s)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:24:32.808181Z","caller":"traceutil/trace.go:171","msg":"trace[1669687509] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"1.41432704s","start":"2026-08-11T07:24:31.393842Z","end":"2026-08-11T07:24:32.808169Z","steps":["trace[1669687509] 'process raft request' (duration: 1.414137483s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.817854479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-11T07:24:32.808445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:29.773675Z","time spent":"3.034751621s","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:24:32.808476Z","caller":"traceutil/trace.go:171","msg":"trace[181282803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3108; }","duration":"2.817946022s","start":"2026-08-11T07:24:29.990522Z","end":"2026-08-11T07:24:32.808468Z","steps":["trace[181282803] 'agreement among raft nodes before linearized reading' (duration: 2.817810637s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:29.990499Z","time spent":"2.817996954s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T07:24:32.808488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.393819Z","time spent":"1.414618919s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:24:32.80861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720934973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T07:24:32.808607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328571665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:24:32.80863Z","caller":"traceutil/trace.go:171","msg":"trace[2015696121] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3108; }","duration":"1.720999855s","start":"2026-08-11T07:24:31.087625Z","end":"2026-08-11T07:24:32.808625Z","steps":["trace[2015696121] 'agreement among raft nodes before linearized reading' (duration: 1.720953053s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.087607Z","time spent":"1.721036656s","remote":"127.0.0.1:45044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":21,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:24:32.808796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824023935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-11T07:24:32.808819Z","caller":"traceutil/trace.go:171","msg":"trace[1455817223] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3108; }","duration":"1.824091326s","start":"2026-08-11T07:24:30.984722Z","end":"2026-08-11T07:24:32.808813Z","steps":["trace[1455817223] 'agreement among raft nodes before linearized reading' (duration: 1.824029624s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.984699Z","time spent":"1.824132958s","remote":"127.0.0.1:45044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-08-11T07:24:32.808643Z","caller":"traceutil/trace.go:171","msg":"trace[727713375] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:3108; }","duration":"1.328639988s","start":"2026-08-11T07:24:31.479989Z","end":"2026-08-11T07:24:32.808629Z","steps":["trace[727713375] 'agreement among raft nodes before linearized reading' (duration: 1.328584956s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.808895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.479981Z","time spent":"1.328898747s","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:24:32.809011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.756432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:24:32.809054Z","caller":"traceutil/trace.go:171","msg":"trace[1330212700] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:3108; }","duration":"789.876277ms","start":"2026-08-11T07:24:32.019168Z","end":"2026-08-11T07:24:32.809044Z","steps":["trace[1330212700] 'agreement among raft nodes before linearized reading' (duration: 789.814954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:32.019139Z","time spent":"789.936819ms","remote":"127.0.0.1:45342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:24:32.809193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267231728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:24:32.80922Z","caller":"traceutil/trace.go:171","msg":"trace[617707432] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3108; }","duration":"1.267312161s","start":"2026-08-11T07:24:31.541901Z","end":"2026-08-11T07:24:32.809214Z","steps":["trace[617707432] 'agreement among raft nodes before linearized reading' (duration: 1.267269379s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.223589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:24:32.80924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.541882Z","time spent":"1.267353652s","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:24:32.80925Z","caller":"traceutil/trace.go:171","msg":"trace[1494251362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3108; }","duration":"898.299122ms","start":"2026-08-11T07:24:31.910941Z","end":"2026-08-11T07:24:32.80924Z","steps":["trace[1494251362] 'agreement among raft nodes before linearized reading' (duration: 898.24861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:31.910924Z","time spent":"898.346044ms","remote":"127.0.0.1:44802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T07:24:32.809383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.495672288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-11T07:24:32.809515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982170614s","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-08-11T07:24:32.809539Z","caller":"traceutil/trace.go:171","msg":"trace[521200069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3108; }","duration":"1.982235527s","start":"2026-08-11T07:24:30.827297Z","end":"2026-08-11T07:24:32.809533Z","steps":["trace[521200069] 'agreement among raft nodes before linearized reading' (duration: 1.982161534s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.827277Z","time spent":"1.982277728s","remote":"127.0.0.1:45120","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-08-11T07:24:32.809573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983004464s","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-08-11T07:24:32.809604Z","caller":"traceutil/trace.go:171","msg":"trace[1122967433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3108; }","duration":"1.983076576s","start":"2026-08-11T07:24:30.82652Z","end":"2026-08-11T07:24:32.809597Z","steps":["trace[1122967433] 'agreement among raft nodes before linearized reading' (duration: 1.982972132s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.8265Z","time spent":"1.983119638s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T07:24:32.809447Z","caller":"traceutil/trace.go:171","msg":"trace[813174536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3108; }","duration":"2.495810712s","start":"2026-08-11T07:24:30.31362Z","end":"2026-08-11T07:24:32.809431Z","steps":["trace[813174536] 'agreement among raft nodes before linearized reading' (duration: 2.495272993s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:24:32.809755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:24:30.313601Z","time spent":"2.496133494s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T07:26:29.49461Z","caller":"traceutil/trace.go:171","msg":"trace[513494658] transaction","detail":"{read_only:false; response_revision:3890; number_of_response:1; }","duration":"111.798943ms","start":"2026-08-11T07:26:29.382771Z","end":"2026-08-11T07:26:29.49457Z","steps":["trace[513494658] 'process raft request' (duration: 76.437972ms)","trace[513494658] 'compare' (duration: 35.032344ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:26:29.63391Z","caller":"traceutil/trace.go:171","msg":"trace[1036512783] linearizableReadLoop","detail":"{readStateIndex:4039; appliedIndex:4035; }","duration":"116.93434ms","start":"2026-08-11T07:26:29.51685Z","end":"2026-08-11T07:26:29.633784Z","steps":["trace[1036512783] 'read index received' (duration: 9.187698ms)","trace[1036512783] 'applied index is now lower than readState.Index' (duration: 107.746012ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:26:29.633906Z","caller":"traceutil/trace.go:171","msg":"trace[642778964] transaction","detail":"{read_only:false; response_revision:3893; number_of_response:1; }","duration":"133.473865ms","start":"2026-08-11T07:26:29.500408Z","end":"2026-08-11T07:26:29.633882Z","steps":["trace[642778964] 'process raft request' (duration: 133.275711ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:26:29.633972Z","caller":"traceutil/trace.go:171","msg":"trace[1705695858] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"131.95417ms","start":"2026-08-11T07:26:29.502009Z","end":"2026-08-11T07:26:29.633963Z","steps":["trace[1705695858] 'process raft request' (duration: 131.713815ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:26:29.633838Z","caller":"traceutil/trace.go:171","msg":"trace[1444685521] transaction","detail":"{read_only:false; response_revision:3892; number_of_response:1; }","duration":"133.459444ms","start":"2026-08-11T07:26:29.500325Z","end":"2026-08-11T07:26:29.633784Z","steps":["trace[1444685521] 'process raft request' (duration: 99.869383ms)","trace[1444685521] 'compare' (duration: 33.364876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:26:29.633939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.074283ms","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-08-11T07:26:29.634408Z","caller":"traceutil/trace.go:171","msg":"trace[501282949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3894; }","duration":"117.592425ms","start":"2026-08-11T07:26:29.516791Z","end":"2026-08-11T07:26:29.634383Z","steps":["trace[501282949] 'agreement among raft nodes before linearized reading' (duration: 116.998501ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:26:55.439885Z","caller":"traceutil/trace.go:171","msg":"trace[338552078] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"106.852646ms","start":"2026-08-11T07:26:55.332987Z","end":"2026-08-11T07:26:55.43984Z","steps":["trace[338552078] 'process raft request' (duration: 86.299732ms)","trace[338552078] 'compare' (duration: 20.309829ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:26:55.440037Z","caller":"traceutil/trace.go:171","msg":"trace[1336279408] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"106.798744ms","start":"2026-08-11T07:26:55.33322Z","end":"2026-08-11T07:26:55.440019Z","steps":["trace[1336279408] 'process raft request' (duration: 106.545129ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:26:57.416023Z","caller":"traceutil/trace.go:171","msg":"trace[2137920878] linearizableReadLoop","detail":"{readStateIndex:4533; appliedIndex:4532; }","duration":"106.817884ms","start":"2026-08-11T07:26:57.309171Z","end":"2026-08-11T07:26:57.415989Z","steps":["trace[2137920878] 'read index received' (duration: 105.931215ms)","trace[2137920878] 'applied index is now lower than readState.Index' (duration: 885.429µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:26:57.416123Z","caller":"traceutil/trace.go:171","msg":"trace[812457797] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"139.151722ms","start":"2026-08-11T07:26:57.276947Z","end":"2026-08-11T07:26:57.416099Z","steps":["trace[812457797] 'process raft request' (duration: 138.282834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:26:57.416263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.035048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:26:57.416375Z","caller":"traceutil/trace.go:171","msg":"trace[1910229099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4366; }","duration":"107.248902ms","start":"2026-08-11T07:26:57.3091Z","end":"2026-08-11T07:26:57.416349Z","steps":["trace[1910229099] 'agreement among raft nodes before linearized reading' (duration: 107.010827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:26:57.43038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.292255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:4344"} {"level":"info","ts":"2026-08-11T07:26:57.430451Z","caller":"traceutil/trace.go:171","msg":"trace[644686319] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4367; }","duration":"109.50769ms","start":"2026-08-11T07:26:57.320931Z","end":"2026-08-11T07:26:57.430439Z","steps":["trace[644686319] 'agreement among raft nodes before linearized reading' (duration: 109.236205ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:00.178454Z","caller":"traceutil/trace.go:171","msg":"trace[847852071] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"150.981017ms","start":"2026-08-11T07:27:00.027441Z","end":"2026-08-11T07:27:00.178422Z","steps":["trace[847852071] 'process raft request' (duration: 87.692408ms)","trace[847852071] 'compare' (duration: 63.133406ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:00.178723Z","caller":"traceutil/trace.go:171","msg":"trace[1951465538] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"149.305212ms","start":"2026-08-11T07:27:00.029391Z","end":"2026-08-11T07:27:00.178696Z","steps":["trace[1951465538] 'process raft request' (duration: 148.988225ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:00.178784Z","caller":"traceutil/trace.go:171","msg":"trace[951717392] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"149.21013ms","start":"2026-08-11T07:27:00.029548Z","end":"2026-08-11T07:27:00.178758Z","steps":["trace[951717392] 'process raft request' (duration: 149.041306ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:03.164702Z","caller":"traceutil/trace.go:171","msg":"trace[882667453] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"144.450769ms","start":"2026-08-11T07:27:03.02022Z","end":"2026-08-11T07:27:03.16467Z","steps":["trace[882667453] 'process raft request' (duration: 144.076461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:03.38219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.735291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:15 size:490667"} {"level":"info","ts":"2026-08-11T07:27:03.382313Z","caller":"traceutil/trace.go:171","msg":"trace[1850178717] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:15; response_revision:4453; }","duration":"125.901715ms","start":"2026-08-11T07:27:03.256379Z","end":"2026-08-11T07:27:03.382281Z","steps":["trace[1850178717] 'range keys from in-memory index tree' (duration: 125.384034ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:05.338726Z","caller":"traceutil/trace.go:171","msg":"trace[1863801669] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"249.414416ms","start":"2026-08-11T07:27:05.089271Z","end":"2026-08-11T07:27:05.338686Z","steps":["trace[1863801669] 'process raft request' (duration: 203.273706ms)","trace[1863801669] 'compare' (duration: 45.705241ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:06.026633Z","caller":"traceutil/trace.go:171","msg":"trace[293240348] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"116.59785ms","start":"2026-08-11T07:27:05.910011Z","end":"2026-08-11T07:27:06.026609Z","steps":["trace[293240348] 'process raft request' (duration: 93.850192ms)","trace[293240348] 'compare' (duration: 22.659296ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:06.556401Z","caller":"traceutil/trace.go:171","msg":"trace[178302703] linearizableReadLoop","detail":"{readStateIndex:4711; appliedIndex:4710; }","duration":"233.549507ms","start":"2026-08-11T07:27:06.322825Z","end":"2026-08-11T07:27:06.556375Z","steps":["trace[178302703] 'read index received' (duration: 165.355526ms)","trace[178302703] 'applied index is now lower than readState.Index' (duration: 68.193081ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:06.556592Z","caller":"traceutil/trace.go:171","msg":"trace[592950002] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"233.787812ms","start":"2026-08-11T07:27:06.322773Z","end":"2026-08-11T07:27:06.556561Z","steps":["trace[592950002] 'process raft request' (duration: 165.397037ms)","trace[592950002] 'compare' (duration: 68.059958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:06.557053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.1931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-11T07:27:06.557153Z","caller":"traceutil/trace.go:171","msg":"trace[1958378682] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4542; }","duration":"234.360253ms","start":"2026-08-11T07:27:06.322778Z","end":"2026-08-11T07:27:06.557138Z","steps":["trace[1958378682] 'agreement among raft nodes before linearized reading' (duration: 233.808232ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:06.56281Z","caller":"traceutil/trace.go:171","msg":"trace[317912714] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"207.667535ms","start":"2026-08-11T07:27:06.355117Z","end":"2026-08-11T07:27:06.562785Z","steps":["trace[317912714] 'process raft request' (duration: 207.583814ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:06.562972Z","caller":"traceutil/trace.go:171","msg":"trace[1096781001] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"239.823246ms","start":"2026-08-11T07:27:06.323125Z","end":"2026-08-11T07:27:06.562948Z","steps":["trace[1096781001] 'process raft request' (duration: 239.405577ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:06.563081Z","caller":"traceutil/trace.go:171","msg":"trace[282262762] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"239.548ms","start":"2026-08-11T07:27:06.323518Z","end":"2026-08-11T07:27:06.563066Z","steps":["trace[282262762] 'process raft request' (duration: 239.128482ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:06.764949Z","caller":"traceutil/trace.go:171","msg":"trace[1013841140] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"150.755496ms","start":"2026-08-11T07:27:06.614158Z","end":"2026-08-11T07:27:06.764914Z","steps":["trace[1013841140] 'process raft request' (duration: 60.86266ms)","trace[1013841140] 'compare' (duration: 89.758353ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:07.268412Z","caller":"traceutil/trace.go:171","msg":"trace[1595830263] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"115.308983ms","start":"2026-08-11T07:27:07.153065Z","end":"2026-08-11T07:27:07.268374Z","steps":["trace[1595830263] 'process raft request' (duration: 115.055368ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:07.274737Z","caller":"traceutil/trace.go:171","msg":"trace[2098599843] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"106.953992ms","start":"2026-08-11T07:27:07.167765Z","end":"2026-08-11T07:27:07.274719Z","steps":["trace[2098599843] 'process raft request' (duration: 106.87279ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:08.036936Z","caller":"traceutil/trace.go:171","msg":"trace[1006906518] linearizableReadLoop","detail":"{readStateIndex:4761; appliedIndex:4760; }","duration":"108.828108ms","start":"2026-08-11T07:27:07.928082Z","end":"2026-08-11T07:27:08.03691Z","steps":["trace[1006906518] 'read index received' (duration: 108.592613ms)","trace[1006906518] 'applied index is now lower than readState.Index' (duration: 234.655µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:08.036964Z","caller":"traceutil/trace.go:171","msg":"trace[1789642368] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"120.851085ms","start":"2026-08-11T07:27:07.916077Z","end":"2026-08-11T07:27:08.036929Z","steps":["trace[1789642368] 'process raft request' (duration: 120.652951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:08.037203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.097074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-11T07:27:08.037249Z","caller":"traceutil/trace.go:171","msg":"trace[955746025] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4590; }","duration":"109.189476ms","start":"2026-08-11T07:27:07.928045Z","end":"2026-08-11T07:27:08.037235Z","steps":["trace[955746025] 'agreement among raft nodes before linearized reading' (duration: 108.971031ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:08.979047Z","caller":"traceutil/trace.go:171","msg":"trace[1931646649] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4766; }","duration":"157.003869ms","start":"2026-08-11T07:27:08.822014Z","end":"2026-08-11T07:27:08.979018Z","steps":["trace[1931646649] 'read index received' (duration: 156.810015ms)","trace[1931646649] 'applied index is now lower than readState.Index' (duration: 192.644µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:08.979339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.296825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-j6lvh\" ","response":"range_response_count:1 size:9674"} {"level":"info","ts":"2026-08-11T07:27:08.979385Z","caller":"traceutil/trace.go:171","msg":"trace[497806841] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-j6lvh; range_end:; response_count:1; response_revision:4596; }","duration":"157.382257ms","start":"2026-08-11T07:27:08.82199Z","end":"2026-08-11T07:27:08.979373Z","steps":["trace[497806841] 'agreement among raft nodes before linearized reading' (duration: 157.161383ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:08.979867Z","caller":"traceutil/trace.go:171","msg":"trace[878205928] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"160.610033ms","start":"2026-08-11T07:27:08.819229Z","end":"2026-08-11T07:27:08.979839Z","steps":["trace[878205928] 'process raft request' (duration: 159.586372ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:09.02545Z","caller":"traceutil/trace.go:171","msg":"trace[134543039] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"201.207082ms","start":"2026-08-11T07:27:08.824213Z","end":"2026-08-11T07:27:09.02542Z","steps":["trace[134543039] 'process raft request' (duration: 201.054979ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:11.559423Z","caller":"traceutil/trace.go:171","msg":"trace[1495496747] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"109.150555ms","start":"2026-08-11T07:27:11.450249Z","end":"2026-08-11T07:27:11.559399Z","steps":["trace[1495496747] 'process raft request' (duration: 108.998002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:13.703712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.871768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:13.703815Z","caller":"traceutil/trace.go:171","msg":"trace[1781628939] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4709; }","duration":"152.034811ms","start":"2026-08-11T07:27:13.551758Z","end":"2026-08-11T07:27:13.703792Z","steps":["trace[1781628939] 'count revisions from in-memory index tree' (duration: 151.787177ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:13.703848Z","caller":"traceutil/trace.go:171","msg":"trace[574482729] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"136.241532ms","start":"2026-08-11T07:27:13.567577Z","end":"2026-08-11T07:27:13.703818Z","steps":["trace[574482729] 'process raft request' (duration: 81.717531ms)","trace[574482729] 'compare' (duration: 54.376859ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:14.152051Z","caller":"traceutil/trace.go:171","msg":"trace[957943500] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"584.192366ms","start":"2026-08-11T07:27:13.567815Z","end":"2026-08-11T07:27:14.152007Z","steps":["trace[957943500] 'process raft request' (duration: 583.931601ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:14.152154Z","caller":"traceutil/trace.go:171","msg":"trace[988813102] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4883; }","duration":"387.692925ms","start":"2026-08-11T07:27:13.764444Z","end":"2026-08-11T07:27:14.152137Z","steps":["trace[988813102] 'read index received' (duration: 387.313368ms)","trace[988813102] 'applied index is now lower than readState.Index' (duration: 378.587µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:14.152293Z","caller":"traceutil/trace.go:171","msg":"trace[887322954] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"510.055148ms","start":"2026-08-11T07:27:13.642226Z","end":"2026-08-11T07:27:14.152281Z","steps":["trace[887322954] 'process raft request' (duration: 509.775523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.152327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.567769Z","time spent":"584.40912ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:27:14.15213Z","caller":"traceutil/trace.go:171","msg":"trace[821710283] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"517.052589ms","start":"2026-08-11T07:27:13.63504Z","end":"2026-08-11T07:27:14.152092Z","steps":["trace[821710283] 'process raft request' (duration: 516.899796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.152459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.642213Z","time spent":"510.123379ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:27:14.152597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.635007Z","time spent":"517.481418ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:27:14.152794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.328129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42c713bc-42ee-4ab5-96bb-f1a3b11cea64\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-11T07:27:14.152883Z","caller":"traceutil/trace.go:171","msg":"trace[1336731773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42c713bc-42ee-4ab5-96bb-f1a3b11cea64; range_end:; response_count:1; response_revision:4713; }","duration":"388.479822ms","start":"2026-08-11T07:27:13.764381Z","end":"2026-08-11T07:27:14.152861Z","steps":["trace[1336731773] 'agreement among raft nodes before linearized reading' (duration: 388.164656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.152933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.764364Z","time spent":"388.560553ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42c713bc-42ee-4ab5-96bb-f1a3b11cea64\" "} {"level":"info","ts":"2026-08-11T07:27:14.270107Z","caller":"traceutil/trace.go:171","msg":"trace[1057895894] linearizableReadLoop","detail":"{readStateIndex:4887; appliedIndex:4886; }","duration":"117.809455ms","start":"2026-08-11T07:27:14.152235Z","end":"2026-08-11T07:27:14.270045Z","steps":["trace[1057895894] 'read index received' (duration: 90.224699ms)","trace[1057895894] 'applied index is now lower than readState.Index' (duration: 27.583856ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:14.270174Z","caller":"traceutil/trace.go:171","msg":"trace[1149805461] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"403.919648ms","start":"2026-08-11T07:27:13.866228Z","end":"2026-08-11T07:27:14.270148Z","steps":["trace[1149805461] 'process raft request' (duration: 376.323022ms)","trace[1149805461] 'compare' (duration: 27.33176ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:14.270335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.866194Z","time spent":"404.063421ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T07:27:14.270376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.793787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:27:14.270387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.497483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T07:27:14.270483Z","caller":"traceutil/trace.go:171","msg":"trace[934715829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4714; }","duration":"210.652126ms","start":"2026-08-11T07:27:14.059814Z","end":"2026-08-11T07:27:14.270466Z","steps":["trace[934715829] 'agreement among raft nodes before linearized reading' (duration: 210.273229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.270569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.01758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-58bc8f7bd5-sw2dm\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-08-11T07:27:14.270608Z","caller":"traceutil/trace.go:171","msg":"trace[1953214407] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-58bc8f7bd5-sw2dm; range_end:; response_count:1; response_revision:4714; }","duration":"406.089642ms","start":"2026-08-11T07:27:13.864508Z","end":"2026-08-11T07:27:14.270598Z","steps":["trace[1953214407] 'agreement among raft nodes before linearized reading' (duration: 405.938919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.27064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.864488Z","time spent":"406.145283ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11279,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-58bc8f7bd5-sw2dm\" "} {"level":"info","ts":"2026-08-11T07:27:14.270426Z","caller":"traceutil/trace.go:171","msg":"trace[27546844] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4714; }","duration":"263.94848ms","start":"2026-08-11T07:27:14.006465Z","end":"2026-08-11T07:27:14.270413Z","steps":["trace[27546844] 'agreement among raft nodes before linearized reading' (duration: 263.765186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.270763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.549976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T07:27:14.270857Z","caller":"traceutil/trace.go:171","msg":"trace[303038512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4714; }","duration":"160.73985ms","start":"2026-08-11T07:27:14.110096Z","end":"2026-08-11T07:27:14.270836Z","steps":["trace[303038512] 'agreement among raft nodes before linearized reading' (duration: 160.127647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.27109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.948181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:27:14.271123Z","caller":"traceutil/trace.go:171","msg":"trace[107748270] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4714; }","duration":"466.019462ms","start":"2026-08-11T07:27:13.805096Z","end":"2026-08-11T07:27:14.271116Z","steps":["trace[107748270] 'agreement among raft nodes before linearized reading' (duration: 465.850459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:14.271181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:13.805089Z","time spent":"466.083364ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":50,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:27:14.670653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.402521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:14.670834Z","caller":"traceutil/trace.go:171","msg":"trace[461116331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4723; }","duration":"145.639276ms","start":"2026-08-11T07:27:14.525167Z","end":"2026-08-11T07:27:14.670806Z","steps":["trace[461116331] 'range keys from in-memory index tree' (duration: 145.231288ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:16.080008Z","caller":"traceutil/trace.go:171","msg":"trace[581624316] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4904; }","duration":"144.558293ms","start":"2026-08-11T07:27:15.935419Z","end":"2026-08-11T07:27:16.079977Z","steps":["trace[581624316] 'read index received' (duration: 144.377069ms)","trace[581624316] 'applied index is now lower than readState.Index' (duration: 179.844µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:16.080098Z","caller":"traceutil/trace.go:171","msg":"trace[1682855928] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"188.895825ms","start":"2026-08-11T07:27:15.891162Z","end":"2026-08-11T07:27:16.080058Z","steps":["trace[1682855928] 'process raft request' (duration: 188.650941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:16.080286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.366908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-11T07:27:16.080339Z","caller":"traceutil/trace.go:171","msg":"trace[1295194139] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4731; }","duration":"135.457621ms","start":"2026-08-11T07:27:15.944867Z","end":"2026-08-11T07:27:16.080325Z","steps":["trace[1295194139] 'agreement among raft nodes before linearized reading' (duration: 135.280597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:16.080724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.897661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:16.080812Z","caller":"traceutil/trace.go:171","msg":"trace[460876557] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4731; }","duration":"145.429901ms","start":"2026-08-11T07:27:15.935362Z","end":"2026-08-11T07:27:16.080792Z","steps":["trace[460876557] 'agreement among raft nodes before linearized reading' (duration: 144.757738ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:24.357063Z","caller":"traceutil/trace.go:171","msg":"trace[687327858] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"360.71535ms","start":"2026-08-11T07:27:23.996304Z","end":"2026-08-11T07:27:24.35702Z","steps":["trace[687327858] 'process raft request' (duration: 358.392304ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:24.357069Z","caller":"traceutil/trace.go:171","msg":"trace[723033200] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"329.065086ms","start":"2026-08-11T07:27:24.027974Z","end":"2026-08-11T07:27:24.357039Z","steps":["trace[723033200] 'read index received' (duration: 326.618928ms)","trace[723033200] 'applied index is now lower than readState.Index' (duration: 2.444828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:24.357339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:23.996281Z","time spent":"360.951445ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:27:24.357483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.479004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:24.357552Z","caller":"traceutil/trace.go:171","msg":"trace[1731545958] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4856; }","duration":"329.597986ms","start":"2026-08-11T07:27:24.027938Z","end":"2026-08-11T07:27:24.357536Z","steps":["trace[1731545958] 'agreement among raft nodes before linearized reading' (duration: 329.136737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:24.357614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:24.027921Z","time spent":"329.679188ms","remote":"127.0.0.1:44978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:27:24.357379Z","caller":"traceutil/trace.go:171","msg":"trace[142174814] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"170.027781ms","start":"2026-08-11T07:27:24.18733Z","end":"2026-08-11T07:27:24.357358Z","steps":["trace[142174814] 'process raft request' (duration: 169.645024ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:24.358491Z","caller":"traceutil/trace.go:171","msg":"trace[662446082] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"171.079442ms","start":"2026-08-11T07:27:24.18739Z","end":"2026-08-11T07:27:24.358469Z","steps":["trace[662446082] 'process raft request' (duration: 169.893339ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:24.358531Z","caller":"traceutil/trace.go:171","msg":"trace[614254526] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"170.867628ms","start":"2026-08-11T07:27:24.18763Z","end":"2026-08-11T07:27:24.358497Z","steps":["trace[614254526] 'process raft request' (duration: 170.49188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.378867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033151709747621149,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T07:27:30.464693Z","caller":"traceutil/trace.go:171","msg":"trace[1481171314] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"841.371507ms","start":"2026-08-11T07:27:29.623288Z","end":"2026-08-11T07:27:30.46466Z","steps":["trace[1481171314] 'process raft request' (duration: 841.167643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.464865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:29.623249Z","time spent":"841.532871ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:27:30.755761Z","caller":"traceutil/trace.go:171","msg":"trace[995952095] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5098; }","duration":"878.401627ms","start":"2026-08-11T07:27:29.877348Z","end":"2026-08-11T07:27:30.755749Z","steps":["trace[995952095] 'read index received' (duration: 587.076879ms)","trace[995952095] 'applied index is now lower than readState.Index' (duration: 291.323988ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:30.755845Z","caller":"traceutil/trace.go:171","msg":"trace[611925852] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"890.84245ms","start":"2026-08-11T07:27:29.864997Z","end":"2026-08-11T07:27:30.755839Z","steps":["trace[611925852] 'process raft request' (duration: 890.627826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.755917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:29.864972Z","time spent":"890.890561ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:27:30.755997Z","caller":"traceutil/trace.go:171","msg":"trace[1025556313] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"376.860803ms","start":"2026-08-11T07:27:30.379129Z","end":"2026-08-11T07:27:30.75599Z","steps":["trace[1025556313] 'process raft request' (duration: 376.545797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.379112Z","time spent":"376.902604ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:27:30.75608Z","caller":"traceutil/trace.go:171","msg":"trace[1403187505] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"376.687879ms","start":"2026-08-11T07:27:30.379387Z","end":"2026-08-11T07:27:30.756075Z","steps":["trace[1403187505] 'process raft request' (duration: 376.310682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.379359Z","time spent":"376.738921ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:27:30.756161Z","caller":"traceutil/trace.go:171","msg":"trace[1186261454] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"375.275702ms","start":"2026-08-11T07:27:30.38088Z","end":"2026-08-11T07:27:30.756156Z","steps":["trace[1186261454] 'process raft request' (duration: 374.839263ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:30.755756Z","caller":"traceutil/trace.go:171","msg":"trace[232151575] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"977.072871ms","start":"2026-08-11T07:27:29.778648Z","end":"2026-08-11T07:27:30.75572Z","steps":["trace[232151575] 'process raft request' (duration: 976.880168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.380846Z","time spent":"375.331303ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:27:30.75633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.621169ms","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":"warn","ts":"2026-08-11T07:27:30.756312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:29.778621Z","time spent":"977.596231ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:27:30.75641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.758314ms","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-08-11T07:27:30.756431Z","caller":"traceutil/trace.go:171","msg":"trace[1361584042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4924; }","duration":"186.809504ms","start":"2026-08-11T07:27:30.569617Z","end":"2026-08-11T07:27:30.756426Z","steps":["trace[1361584042] 'agreement among raft nodes before linearized reading' (duration: 186.757243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.755081ms","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-08-11T07:27:30.756521Z","caller":"traceutil/trace.go:171","msg":"trace[466356989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4924; }","duration":"229.812362ms","start":"2026-08-11T07:27:30.526704Z","end":"2026-08-11T07:27:30.756517Z","steps":["trace[466356989] 'agreement among raft nodes before linearized reading' (duration: 229.766161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.169912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:27:30.756571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.595928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:30.75659Z","caller":"traceutil/trace.go:171","msg":"trace[1501750131] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4924; }","duration":"320.667509ms","start":"2026-08-11T07:27:30.435918Z","end":"2026-08-11T07:27:30.756585Z","steps":["trace[1501750131] 'agreement among raft nodes before linearized reading' (duration: 320.637629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.435872Z","time spent":"320.728851ms","remote":"127.0.0.1:45186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:27:30.756564Z","caller":"traceutil/trace.go:171","msg":"trace[2090181597] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4924; }","duration":"879.248633ms","start":"2026-08-11T07:27:29.877292Z","end":"2026-08-11T07:27:30.756541Z","steps":["trace[2090181597] 'agreement among raft nodes before linearized reading' (duration: 879.184242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:29.877273Z","time spent":"879.396427ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-11T07:27:30.756715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.884567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:30.75674Z","caller":"traceutil/trace.go:171","msg":"trace[2086466981] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4924; }","duration":"351.922368ms","start":"2026-08-11T07:27:30.40481Z","end":"2026-08-11T07:27:30.756733Z","steps":["trace[2086466981] 'agreement among raft nodes before linearized reading' (duration: 351.884627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.404804Z","time spent":"351.952269ms","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:27:30.756357Z","caller":"traceutil/trace.go:171","msg":"trace[2052007727] 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:4924; }","duration":"669.686241ms","start":"2026-08-11T07:27:30.086661Z","end":"2026-08-11T07:27:30.756347Z","steps":["trace[2052007727] 'agreement among raft nodes before linearized reading' (duration: 669.604959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.086638Z","time spent":"670.227101ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-11T07:27:30.756899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.518776ms","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-08-11T07:27:30.75692Z","caller":"traceutil/trace.go:171","msg":"trace[1431644546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4924; }","duration":"357.575408ms","start":"2026-08-11T07:27:30.399339Z","end":"2026-08-11T07:27:30.756915Z","steps":["trace[1431644546] 'agreement among raft nodes before linearized reading' (duration: 357.520897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.756937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.399325Z","time spent":"357.608508ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T07:27:30.757018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.52982ms","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-08-11T07:27:30.757038Z","caller":"traceutil/trace.go:171","msg":"trace[1476307918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4924; }","duration":"370.573831ms","start":"2026-08-11T07:27:30.386459Z","end":"2026-08-11T07:27:30.757033Z","steps":["trace[1476307918] 'agreement among raft nodes before linearized reading' (duration: 370.52721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.757053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.386449Z","time spent":"370.599801ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T07:27:30.757128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.120401ms","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-08-11T07:27:30.757159Z","caller":"traceutil/trace.go:171","msg":"trace[407002219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4924; }","duration":"372.192752ms","start":"2026-08-11T07:27:30.384962Z","end":"2026-08-11T07:27:30.757154Z","steps":["trace[407002219] 'agreement among raft nodes before linearized reading' (duration: 372.132821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.757175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.384942Z","time spent":"372.228493ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T07:27:30.757241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.672365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:27:30.75726Z","caller":"traceutil/trace.go:171","msg":"trace[1877280560] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4924; }","duration":"421.734256ms","start":"2026-08-11T07:27:30.335521Z","end":"2026-08-11T07:27:30.757255Z","steps":["trace[1877280560] 'agreement among raft nodes before linearized reading' (duration: 421.703486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.757275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:27:30.3355Z","time spent":"421.771477ms","remote":"127.0.0.1:45128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:27:30.895186Z","caller":"traceutil/trace.go:171","msg":"trace[950430137] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"134.402125ms","start":"2026-08-11T07:27:30.760751Z","end":"2026-08-11T07:27:30.895154Z","steps":["trace[950430137] 'process raft request' (duration: 110.387267ms)","trace[950430137] 'compare' (duration: 23.878895ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:30.961985Z","caller":"traceutil/trace.go:171","msg":"trace[450598220] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"201.058411ms","start":"2026-08-11T07:27:30.760897Z","end":"2026-08-11T07:27:30.961955Z","steps":["trace[450598220] 'process raft request' (duration: 200.825907ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:30.962461Z","caller":"traceutil/trace.go:171","msg":"trace[1595206603] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"200.732456ms","start":"2026-08-11T07:27:30.761705Z","end":"2026-08-11T07:27:30.962438Z","steps":["trace[1595206603] 'process raft request' (duration: 200.190875ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:30.962493Z","caller":"traceutil/trace.go:171","msg":"trace[1708718513] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5104; }","duration":"200.194366ms","start":"2026-08-11T07:27:30.762283Z","end":"2026-08-11T07:27:30.962478Z","steps":["trace[1708718513] 'read index received' (duration: 109.014592ms)","trace[1708718513] 'applied index is now lower than readState.Index' (duration: 91.178864ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:30.962578Z","caller":"traceutil/trace.go:171","msg":"trace[1502743746] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"200.816527ms","start":"2026-08-11T07:27:30.761726Z","end":"2026-08-11T07:27:30.962543Z","steps":["trace[1502743746] 'process raft request' (duration: 200.534771ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:30.962896Z","caller":"traceutil/trace.go:171","msg":"trace[454062986] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"200.399359ms","start":"2026-08-11T07:27:30.762477Z","end":"2026-08-11T07:27:30.962877Z","steps":["trace[454062986] 'process raft request' (duration: 199.949581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:30.96323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.9238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-11T07:27:30.963387Z","caller":"traceutil/trace.go:171","msg":"trace[680492354] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4929; }","duration":"201.129973ms","start":"2026-08-11T07:27:30.762237Z","end":"2026-08-11T07:27:30.963367Z","steps":["trace[680492354] 'agreement among raft nodes before linearized reading' (duration: 200.421909ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:52.991233Z","caller":"traceutil/trace.go:171","msg":"trace[1854807841] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"152.595483ms","start":"2026-08-11T07:27:52.838612Z","end":"2026-08-11T07:27:52.991208Z","steps":["trace[1854807841] 'process raft request' (duration: 152.44218ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:52.992976Z","caller":"traceutil/trace.go:171","msg":"trace[1432387055] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5249; }","duration":"141.937675ms","start":"2026-08-11T07:27:52.850998Z","end":"2026-08-11T07:27:52.992936Z","steps":["trace[1432387055] 'read index received' (duration: 140.122831ms)","trace[1432387055] 'applied index is now lower than readState.Index' (duration: 1.813944ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:52.993069Z","caller":"traceutil/trace.go:171","msg":"trace[2020432535] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"154.124482ms","start":"2026-08-11T07:27:52.838916Z","end":"2026-08-11T07:27:52.99304Z","steps":["trace[2020432535] 'process raft request' (duration: 153.839946ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:52.993222Z","caller":"traceutil/trace.go:171","msg":"trace[1504307740] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"153.384918ms","start":"2026-08-11T07:27:52.839823Z","end":"2026-08-11T07:27:52.993208Z","steps":["trace[1504307740] 'process raft request' (duration: 153.042851ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:52.993484Z","caller":"traceutil/trace.go:171","msg":"trace[2051929020] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"149.73585ms","start":"2026-08-11T07:27:52.843737Z","end":"2026-08-11T07:27:52.993473Z","steps":["trace[2051929020] 'process raft request' (duration: 149.162719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:52.993749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.741699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T07:27:52.9938Z","caller":"traceutil/trace.go:171","msg":"trace[1009660768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5068; }","duration":"142.829041ms","start":"2026-08-11T07:27:52.85096Z","end":"2026-08-11T07:27:52.993789Z","steps":["trace[1009660768] 'agreement among raft nodes before linearized reading' (duration: 142.684138ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:53.301219Z","caller":"traceutil/trace.go:171","msg":"trace[411968111] linearizableReadLoop","detail":"{readStateIndex:5255; appliedIndex:5254; }","duration":"124.500325ms","start":"2026-08-11T07:27:53.176692Z","end":"2026-08-11T07:27:53.301193Z","steps":["trace[411968111] 'read index received' (duration: 39.040226ms)","trace[411968111] 'applied index is now lower than readState.Index' (duration: 85.458809ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:53.301297Z","caller":"traceutil/trace.go:171","msg":"trace[1694028886] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"187.481926ms","start":"2026-08-11T07:27:53.113781Z","end":"2026-08-11T07:27:53.301262Z","steps":["trace[1694028886] 'process raft request' (duration: 102.012197ms)","trace[1694028886] 'compare' (duration: 85.146263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:53.301568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.20906ms","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-08-11T07:27:53.301653Z","caller":"traceutil/trace.go:171","msg":"trace[725545383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5070; }","duration":"124.301711ms","start":"2026-08-11T07:27:53.177332Z","end":"2026-08-11T07:27:53.301633Z","steps":["trace[725545383] 'agreement among raft nodes before linearized reading' (duration: 124.042686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:53.301818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.065657ms","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-08-11T07:27:53.302183Z","caller":"traceutil/trace.go:171","msg":"trace[296898906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5070; }","duration":"124.464065ms","start":"2026-08-11T07:27:53.177686Z","end":"2026-08-11T07:27:53.30215Z","steps":["trace[296898906] 'agreement among raft nodes before linearized reading' (duration: 123.986696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:53.302516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.416102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T07:27:53.30256Z","caller":"traceutil/trace.go:171","msg":"trace[1998228296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5070; }","duration":"125.906821ms","start":"2026-08-11T07:27:53.176642Z","end":"2026-08-11T07:27:53.302549Z","steps":["trace[1998228296] 'agreement among raft nodes before linearized reading' (duration: 124.701739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:27:53.580219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.665687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:27:53.580435Z","caller":"traceutil/trace.go:171","msg":"trace[1473670790] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"256.330866ms","start":"2026-08-11T07:27:53.324079Z","end":"2026-08-11T07:27:53.58041Z","steps":["trace[1473670790] 'process raft request' (duration: 151.412645ms)","trace[1473670790] 'compare' (duration: 104.521834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:27:54.062664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.714276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:27:54.06273Z","caller":"traceutil/trace.go:171","msg":"trace[104942453] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:5074; }","duration":"246.832159ms","start":"2026-08-11T07:27:53.815884Z","end":"2026-08-11T07:27:54.062716Z","steps":["trace[104942453] 'count revisions from in-memory index tree' (duration: 246.641565ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:55.514797Z","caller":"traceutil/trace.go:171","msg":"trace[2055975986] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"117.64497ms","start":"2026-08-11T07:27:55.39712Z","end":"2026-08-11T07:27:55.514765Z","steps":["trace[2055975986] 'process raft request' (duration: 52.066145ms)","trace[2055975986] 'compare' (duration: 65.255349ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:55.5148Z","caller":"traceutil/trace.go:171","msg":"trace[337927334] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"117.323514ms","start":"2026-08-11T07:27:55.397443Z","end":"2026-08-11T07:27:55.514766Z","steps":["trace[337927334] 'process raft request' (duration: 117.217552ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:27:57.203109Z","caller":"traceutil/trace.go:171","msg":"trace[1345167904] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"140.194718ms","start":"2026-08-11T07:27:57.062885Z","end":"2026-08-11T07:27:57.203079Z","steps":["trace[1345167904] 'process raft request' (duration: 109.810767ms)","trace[1345167904] 'compare' (duration: 30.136436ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:27:57.203275Z","caller":"traceutil/trace.go:171","msg":"trace[404287749] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"140.203508ms","start":"2026-08-11T07:27:57.063047Z","end":"2026-08-11T07:27:57.203251Z","steps":["trace[404287749] 'process raft request' (duration: 139.958223ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:28:23.634645Z","caller":"traceutil/trace.go:171","msg":"trace[1018347924] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5543; }","duration":"117.804513ms","start":"2026-08-11T07:28:23.516815Z","end":"2026-08-11T07:28:23.63462Z","steps":["trace[1018347924] 'read index received' (duration: 113.413385ms)","trace[1018347924] 'applied index is now lower than readState.Index' (duration: 4.389588ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:28:23.634764Z","caller":"traceutil/trace.go:171","msg":"trace[1418869585] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"178.006968ms","start":"2026-08-11T07:28:23.456742Z","end":"2026-08-11T07:28:23.634749Z","steps":["trace[1418869585] 'process raft request' (duration: 173.476197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:28:23.635191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.343523ms","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-08-11T07:28:23.635287Z","caller":"traceutil/trace.go:171","msg":"trace[1488971230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5350; }","duration":"118.490656ms","start":"2026-08-11T07:28:23.516776Z","end":"2026-08-11T07:28:23.635266Z","steps":["trace[1488971230] 'agreement among raft nodes before linearized reading' (duration: 118.102939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:28:23.651041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.807108ms","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-08-11T07:28:23.651085Z","caller":"traceutil/trace.go:171","msg":"trace[1560739296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5351; }","duration":"132.87898ms","start":"2026-08-11T07:28:23.518196Z","end":"2026-08-11T07:28:23.651075Z","steps":["trace[1560739296] 'agreement among raft nodes before linearized reading' (duration: 132.718737ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:28:38.704415Z","caller":"traceutil/trace.go:171","msg":"trace[785663594] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"127.324682ms","start":"2026-08-11T07:28:38.57707Z","end":"2026-08-11T07:28:38.704395Z","steps":["trace[785663594] 'process raft request' (duration: 66.50393ms)","trace[785663594] 'compare' (duration: 60.543298ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:28:38.928428Z","caller":"traceutil/trace.go:171","msg":"trace[2064417665] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"164.441775ms","start":"2026-08-11T07:28:38.763926Z","end":"2026-08-11T07:28:38.928368Z","steps":["trace[2064417665] 'process raft request' (duration: 72.575106ms)","trace[2064417665] 'compare' (duration: 91.095466ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:28:52.998138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-08-11T07:28:53.368833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"369.245892ms","hash":660449001,"current-db-size-bytes":53358592,"current-db-size":"53 MB","current-db-size-in-use-bytes":29044736,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-11T07:28:53.368927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":660449001,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-08-11T07:28:59.523033Z","caller":"traceutil/trace.go:171","msg":"trace[542102969] linearizableReadLoop","detail":"{readStateIndex:5813; appliedIndex:5812; }","duration":"109.856479ms","start":"2026-08-11T07:28:59.41315Z","end":"2026-08-11T07:28:59.523006Z","steps":["trace[542102969] 'read index received' (duration: 33.988852ms)","trace[542102969] 'applied index is now lower than readState.Index' (duration: 75.866387ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:28:59.523183Z","caller":"traceutil/trace.go:171","msg":"trace[1887422659] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"134.916842ms","start":"2026-08-11T07:28:59.388253Z","end":"2026-08-11T07:28:59.52317Z","steps":["trace[1887422659] 'process raft request' (duration: 58.969783ms)","trace[1887422659] 'compare' (duration: 75.647954ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:28:59.523329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.982742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:28:59.523412Z","caller":"traceutil/trace.go:171","msg":"trace[982271946] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5611; }","duration":"110.083943ms","start":"2026-08-11T07:28:59.413309Z","end":"2026-08-11T07:28:59.523393Z","steps":["trace[982271946] 'agreement among raft nodes before linearized reading' (duration: 109.947551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:28:59.523691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.506911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T07:28:59.523748Z","caller":"traceutil/trace.go:171","msg":"trace[1607526445] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5611; }","duration":"110.635362ms","start":"2026-08-11T07:28:59.4131Z","end":"2026-08-11T07:28:59.523735Z","steps":["trace[1607526445] 'agreement among raft nodes before linearized reading' (duration: 110.416198ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:28:59.737479Z","caller":"traceutil/trace.go:171","msg":"trace[1091417925] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"134.785529ms","start":"2026-08-11T07:28:59.602668Z","end":"2026-08-11T07:28:59.737454Z","steps":["trace[1091417925] 'process raft request' (duration: 134.717258ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:28:59.737859Z","caller":"traceutil/trace.go:171","msg":"trace[339653123] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"146.551177ms","start":"2026-08-11T07:28:59.591294Z","end":"2026-08-11T07:28:59.737845Z","steps":["trace[339653123] 'process raft request' (duration: 53.415729ms)","trace[339653123] 'compare' (duration: 92.353124ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:28:59.737989Z","caller":"traceutil/trace.go:171","msg":"trace[298749881] linearizableReadLoop","detail":"{readStateIndex:5814; appliedIndex:5813; }","duration":"140.14714ms","start":"2026-08-11T07:28:59.597833Z","end":"2026-08-11T07:28:59.73798Z","steps":["trace[298749881] 'read index received' (duration: 46.89304ms)","trace[298749881] 'applied index is now lower than readState.Index' (duration: 93.25273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:28:59.738176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.321883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-08-11T07:28:59.738217Z","caller":"traceutil/trace.go:171","msg":"trace[2100835861] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5613; }","duration":"140.393414ms","start":"2026-08-11T07:28:59.597811Z","end":"2026-08-11T07:28:59.738205Z","steps":["trace[2100835861] 'agreement among raft nodes before linearized reading' (duration: 140.248091ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:29:03.90033Z","caller":"traceutil/trace.go:171","msg":"trace[1754757539] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"151.736512ms","start":"2026-08-11T07:29:03.74857Z","end":"2026-08-11T07:29:03.900306Z","steps":["trace[1754757539] 'process raft request' (duration: 142.988535ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:29:03.900526Z","caller":"traceutil/trace.go:171","msg":"trace[709085804] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"150.699135ms","start":"2026-08-11T07:29:03.749807Z","end":"2026-08-11T07:29:03.900506Z","steps":["trace[709085804] 'process raft request' (duration: 150.39602ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:29:18.053853Z","caller":"traceutil/trace.go:171","msg":"trace[1436588696] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"104.849738ms","start":"2026-08-11T07:29:17.948983Z","end":"2026-08-11T07:29:18.053832Z","steps":["trace[1436588696] 'process raft request' (duration: 104.724456ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:29:40.487603Z","caller":"traceutil/trace.go:171","msg":"trace[177136157] linearizableReadLoop","detail":"{readStateIndex:6064; appliedIndex:6063; }","duration":"135.750608ms","start":"2026-08-11T07:29:40.351828Z","end":"2026-08-11T07:29:40.487578Z","steps":["trace[177136157] 'read index received' (duration: 95.961116ms)","trace[177136157] 'applied index is now lower than readState.Index' (duration: 39.788172ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:29:40.487688Z","caller":"traceutil/trace.go:171","msg":"trace[2133812310] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"187.029165ms","start":"2026-08-11T07:29:40.300619Z","end":"2026-08-11T07:29:40.487648Z","steps":["trace[2133812310] 'process raft request' (duration: 147.096131ms)","trace[2133812310] 'compare' (duration: 39.542648ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:29:40.488034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.095994ms","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":"warn","ts":"2026-08-11T07:29:40.488054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.844261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-11T07:29:40.488086Z","caller":"traceutil/trace.go:171","msg":"trace[1697466996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5853; }","duration":"100.185415ms","start":"2026-08-11T07:29:40.387888Z","end":"2026-08-11T07:29:40.488073Z","steps":["trace[1697466996] 'agreement among raft nodes before linearized reading' (duration: 99.960271ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:29:40.488107Z","caller":"traceutil/trace.go:171","msg":"trace[1023531381] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5853; }","duration":"109.956252ms","start":"2026-08-11T07:29:40.378136Z","end":"2026-08-11T07:29:40.488093Z","steps":["trace[1023531381] 'agreement among raft nodes before linearized reading' (duration: 109.794399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:29:40.488307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.47668ms","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-08-11T07:29:40.488358Z","caller":"traceutil/trace.go:171","msg":"trace[2103339472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5853; }","duration":"136.568981ms","start":"2026-08-11T07:29:40.351774Z","end":"2026-08-11T07:29:40.488343Z","steps":["trace[2103339472] 'agreement among raft nodes before linearized reading' (duration: 135.957451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:29:40.488569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.39336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T07:29:40.489323Z","caller":"traceutil/trace.go:171","msg":"trace[1890037758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5853; }","duration":"111.840103ms","start":"2026-08-11T07:29:40.377464Z","end":"2026-08-11T07:29:40.489304Z","steps":["trace[1890037758] 'agreement among raft nodes before linearized reading' (duration: 110.360729ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:19.239982Z","caller":"traceutil/trace.go:171","msg":"trace[1638849234] transaction","detail":"{read_only:false; response_revision:6556; number_of_response:1; }","duration":"175.611752ms","start":"2026-08-11T07:31:19.064348Z","end":"2026-08-11T07:31:19.23996Z","steps":["trace[1638849234] 'process raft request' (duration: 175.569242ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:19.240297Z","caller":"traceutil/trace.go:171","msg":"trace[927628736] transaction","detail":"{read_only:false; response_revision:6555; number_of_response:1; }","duration":"175.886177ms","start":"2026-08-11T07:31:19.064233Z","end":"2026-08-11T07:31:19.240119Z","steps":["trace[927628736] 'process raft request' (duration: 139.953844ms)","trace[927628736] 'compare' (duration: 35.617268ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:31:20.033502Z","caller":"traceutil/trace.go:171","msg":"trace[215845280] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"113.301041ms","start":"2026-08-11T07:31:19.920172Z","end":"2026-08-11T07:31:20.033473Z","steps":["trace[215845280] 'process raft request' (duration: 113.147939ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:20.424465Z","caller":"traceutil/trace.go:171","msg":"trace[1471389002] transaction","detail":"{read_only:false; response_revision:6562; number_of_response:1; }","duration":"385.773253ms","start":"2026-08-11T07:31:20.038659Z","end":"2026-08-11T07:31:20.424432Z","steps":["trace[1471389002] 'process raft request' (duration: 385.502449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:20.425627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:20.038639Z","time spent":"385.902556ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T07:31:20.689919Z","caller":"traceutil/trace.go:171","msg":"trace[1614026976] linearizableReadLoop","detail":"{readStateIndex:6799; appliedIndex:6798; }","duration":"154.825627ms","start":"2026-08-11T07:31:20.53505Z","end":"2026-08-11T07:31:20.689875Z","steps":["trace[1614026976] 'read index received' (duration: 154.723806ms)","trace[1614026976] 'applied index is now lower than readState.Index' (duration: 100.531µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:20.690205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.126112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-08-11T07:31:20.690367Z","caller":"traceutil/trace.go:171","msg":"trace[907763511] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6563; }","duration":"155.260964ms","start":"2026-08-11T07:31:20.535011Z","end":"2026-08-11T07:31:20.690272Z","steps":["trace[907763511] 'agreement among raft nodes before linearized reading' (duration: 155.008911ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:20.690832Z","caller":"traceutil/trace.go:171","msg":"trace[935883983] transaction","detail":"{read_only:false; response_revision:6563; number_of_response:1; }","duration":"167.815624ms","start":"2026-08-11T07:31:20.522144Z","end":"2026-08-11T07:31:20.689959Z","steps":["trace[935883983] 'process raft request' (duration: 167.57069ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:21.068942Z","caller":"traceutil/trace.go:171","msg":"trace[52610270] transaction","detail":"{read_only:false; response_revision:6564; number_of_response:1; }","duration":"533.149148ms","start":"2026-08-11T07:31:20.535769Z","end":"2026-08-11T07:31:21.068919Z","steps":["trace[52610270] 'process raft request' (duration: 532.804833ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:21.069158Z","caller":"traceutil/trace.go:171","msg":"trace[448072886] linearizableReadLoop","detail":"{readStateIndex:6801; appliedIndex:6799; }","duration":"379.149183ms","start":"2026-08-11T07:31:20.689982Z","end":"2026-08-11T07:31:21.069131Z","steps":["trace[448072886] 'read index received' (duration: 378.667896ms)","trace[448072886] 'applied index is now lower than readState.Index' (duration: 479.157µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:21.069339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.911773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T07:31:21.069435Z","caller":"traceutil/trace.go:171","msg":"trace[469101077] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:6565; }","duration":"519.082475ms","start":"2026-08-11T07:31:20.550331Z","end":"2026-08-11T07:31:21.069414Z","steps":["trace[469101077] 'agreement among raft nodes before linearized reading' (duration: 518.877142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.069446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.880915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-11T07:31:21.069491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:20.550317Z","time spent":"519.162037ms","remote":"127.0.0.1:45242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-11T07:31:21.069513Z","caller":"traceutil/trace.go:171","msg":"trace[1125687605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6565; }","duration":"184.005128ms","start":"2026-08-11T07:31:20.885494Z","end":"2026-08-11T07:31:21.069499Z","steps":["trace[1125687605] 'agreement among raft nodes before linearized reading' (duration: 183.808235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.069504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.974334ms","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-08-11T07:31:21.069562Z","caller":"traceutil/trace.go:171","msg":"trace[399946900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6565; }","duration":"152.043185ms","start":"2026-08-11T07:31:20.917502Z","end":"2026-08-11T07:31:21.069545Z","steps":["trace[399946900] 'agreement among raft nodes before linearized reading' (duration: 151.862942ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:21.070064Z","caller":"traceutil/trace.go:171","msg":"trace[1453322941] transaction","detail":"{read_only:false; response_revision:6565; number_of_response:1; }","duration":"414.301524ms","start":"2026-08-11T07:31:20.654876Z","end":"2026-08-11T07:31:21.069178Z","steps":["trace[1453322941] 'process raft request' (duration: 413.983209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.070187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:20.654852Z","time spent":"415.269349ms","remote":"127.0.0.1:45096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:31:21.072497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:20.535759Z","time spent":"533.34106ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:31:21.382426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.724429ms","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-08-11T07:31:21.382557Z","caller":"traceutil/trace.go:171","msg":"trace[2021218887] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6565; }","duration":"198.883051ms","start":"2026-08-11T07:31:21.183655Z","end":"2026-08-11T07:31:21.382538Z","steps":["trace[2021218887] 'range keys from in-memory index tree' (duration: 198.701788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.382852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.165474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T07:31:21.383007Z","caller":"traceutil/trace.go:171","msg":"trace[469356924] linearizableReadLoop","detail":"{readStateIndex:6803; appliedIndex:6801; }","duration":"275.735871ms","start":"2026-08-11T07:31:21.107255Z","end":"2026-08-11T07:31:21.382991Z","steps":["trace[469356924] 'read index received' (duration: 86.328013ms)","trace[469356924] 'applied index is now lower than readState.Index' (duration: 189.406958ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:31:21.383159Z","caller":"traceutil/trace.go:171","msg":"trace[1737048778] transaction","detail":"{read_only:false; response_revision:6567; number_of_response:1; }","duration":"307.262316ms","start":"2026-08-11T07:31:21.075869Z","end":"2026-08-11T07:31:21.383131Z","steps":["trace[1737048778] 'process raft request' (duration: 307.077113ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:21.383202Z","caller":"traceutil/trace.go:171","msg":"trace[335044458] transaction","detail":"{read_only:false; response_revision:6566; number_of_response:1; }","duration":"307.771864ms","start":"2026-08-11T07:31:21.075401Z","end":"2026-08-11T07:31:21.383173Z","steps":["trace[335044458] 'process raft request' (duration: 118.210583ms)","trace[335044458] 'compare' (duration: 189.053393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:21.383288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:21.075847Z","time spent":"307.371628ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:31:21.383311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:21.075382Z","time spent":"307.871236ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:31:21.383332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.048746ms","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-08-11T07:31:21.383418Z","caller":"traceutil/trace.go:171","msg":"trace[889713252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6567; }","duration":"276.177097ms","start":"2026-08-11T07:31:21.10722Z","end":"2026-08-11T07:31:21.383398Z","steps":["trace[889713252] 'agreement among raft nodes before linearized reading' (duration: 275.910603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.752709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.390245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-11T07:31:21.752934Z","caller":"traceutil/trace.go:171","msg":"trace[1149336594] linearizableReadLoop","detail":"{readStateIndex:6804; appliedIndex:6803; }","duration":"369.87747ms","start":"2026-08-11T07:31:21.383038Z","end":"2026-08-11T07:31:21.752915Z","steps":["trace[1149336594] 'read index received' (duration: 92.536666ms)","trace[1149336594] 'applied index is now lower than readState.Index' (duration: 277.339894ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:31:21.753298Z","caller":"traceutil/trace.go:171","msg":"trace[1385657413] transaction","detail":"{read_only:false; response_revision:6568; number_of_response:1; }","duration":"366.046662ms","start":"2026-08-11T07:31:21.38724Z","end":"2026-08-11T07:31:21.753287Z","steps":["trace[1385657413] 'process raft request' (duration: 365.579545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T07:31:21.7534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:21.38722Z","time spent":"366.115983ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T07:31:21.801211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.683124ms","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-08-11T07:31:21.801259Z","caller":"traceutil/trace.go:171","msg":"trace[1003344452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6568; }","duration":"557.769335ms","start":"2026-08-11T07:31:21.243478Z","end":"2026-08-11T07:31:21.801247Z","steps":["trace[1003344452] 'agreement among raft nodes before linearized reading' (duration: 509.780101ms)","trace[1003344452] 'range keys from in-memory index tree' (duration: 47.877482ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:21.801285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:21.243456Z","time spent":"557.823436ms","remote":"127.0.0.1:45120","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-08-11T07:31:21.801374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.800401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T07:31:21.801434Z","caller":"traceutil/trace.go:171","msg":"trace[908641861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6568; }","duration":"556.887793ms","start":"2026-08-11T07:31:21.244531Z","end":"2026-08-11T07:31:21.801419Z","steps":["trace[908641861] 'agreement among raft nodes before linearized reading' (duration: 508.711346ms)","trace[908641861] 'range keys from in-memory index tree' (duration: 48.018374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:21.801464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.638738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T07:31:21.801468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T07:31:21.244524Z","time spent":"556.937573ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-11T07:31:21.801491Z","caller":"traceutil/trace.go:171","msg":"trace[213470272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6569; }","duration":"275.73397ms","start":"2026-08-11T07:31:21.525751Z","end":"2026-08-11T07:31:21.801485Z","steps":["trace[213470272] 'agreement among raft nodes before linearized reading' (duration: 275.680729ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:21.801608Z","caller":"traceutil/trace.go:171","msg":"trace[2022362101] transaction","detail":"{read_only:false; response_revision:6569; number_of_response:1; }","duration":"200.320732ms","start":"2026-08-11T07:31:21.601265Z","end":"2026-08-11T07:31:21.801586Z","steps":["trace[2022362101] 'process raft request' (duration: 177.929664ms)","trace[2022362101] 'compare' (duration: 21.974982ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T07:31:29.290608Z","caller":"traceutil/trace.go:171","msg":"trace[1408475622] linearizableReadLoop","detail":"{readStateIndex:6859; appliedIndex:6858; }","duration":"111.442846ms","start":"2026-08-11T07:31:29.179143Z","end":"2026-08-11T07:31:29.290586Z","steps":["trace[1408475622] 'read index received' (duration: 111.289934ms)","trace[1408475622] 'applied index is now lower than readState.Index' (duration: 151.772µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:29.290964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.799691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-gfpwk.18caaf367d8f0584\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-08-11T07:31:29.29082Z","caller":"traceutil/trace.go:171","msg":"trace[715545222] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"140.803137ms","start":"2026-08-11T07:31:29.149977Z","end":"2026-08-11T07:31:29.29078Z","steps":["trace[715545222] 'process raft request' (duration: 140.468212ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:29.291011Z","caller":"traceutil/trace.go:171","msg":"trace[73216731] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-gfpwk.18caaf367d8f0584; range_end:; response_count:1; response_revision:6620; }","duration":"111.892892ms","start":"2026-08-11T07:31:29.179105Z","end":"2026-08-11T07:31:29.290998Z","steps":["trace[73216731] 'agreement among raft nodes before linearized reading' (duration: 111.569407ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:42.056415Z","caller":"traceutil/trace.go:171","msg":"trace[1754780628] linearizableReadLoop","detail":"{readStateIndex:6967; appliedIndex:6966; }","duration":"100.280311ms","start":"2026-08-11T07:31:41.956117Z","end":"2026-08-11T07:31:42.056397Z","steps":["trace[1754780628] 'read index received' (duration: 100.155319ms)","trace[1754780628] 'applied index is now lower than readState.Index' (duration: 124.412µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T07:31:42.056548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.414153ms","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-08-11T07:31:42.056566Z","caller":"traceutil/trace.go:171","msg":"trace[1634013402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6724; }","duration":"100.481964ms","start":"2026-08-11T07:31:41.956077Z","end":"2026-08-11T07:31:42.056559Z","steps":["trace[1634013402] 'agreement among raft nodes before linearized reading' (duration: 100.382572ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:31:42.056593Z","caller":"traceutil/trace.go:171","msg":"trace[762898894] transaction","detail":"{read_only:false; response_revision:6724; number_of_response:1; }","duration":"118.031236ms","start":"2026-08-11T07:31:41.938538Z","end":"2026-08-11T07:31:42.056569Z","steps":["trace[762898894] 'process raft request' (duration: 117.748602ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T07:33:53.005143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5561} {"level":"info","ts":"2026-08-11T07:33:53.0881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5561,"took":"81.404753ms","hash":4247878778,"current-db-size-bytes":53358592,"current-db-size":"53 MB","current-db-size-in-use-bytes":18509824,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-11T07:33:53.088183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4247878778,"revision":5561,"compact-revision":2908} {"level":"warn","ts":"2026-08-11T07:33:53.512923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.461429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cert-manager-controller-orders\" ","response":"range_response_count:1 size:1154"} {"level":"info","ts":"2026-08-11T07:33:53.513018Z","caller":"traceutil/trace.go:171","msg":"trace[1280456486] range","detail":"{range_begin:/registry/clusterrolebindings/cert-manager-controller-orders; range_end:; response_count:1; response_revision:7414; }","duration":"111.611514ms","start":"2026-08-11T07:33:53.401384Z","end":"2026-08-11T07:33:53.512996Z","steps":["trace[1280456486] 'range keys from in-memory index tree' (duration: 111.337896ms)"],"step_count":1}