{"level":"warn","ts":"2026-08-10T22:25:59.636044Z","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-10T22:25:59.636162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-10T22:25:59.636226Z","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-10T22:25:59.636239Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-10T22:25:59.636258Z","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-10T22:25:59.636884Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-08-10T22:25:59.636962Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-10T22:25:59.639949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.786963ms"} {"level":"info","ts":"2026-08-10T22:25:59.64636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-10T22:25:59.646445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:25:59.646475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-10T22:25:59.646489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:25:59.646506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-10T22:25:59.646547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-10T22:25:59.658343Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:25:59.659589Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:25:59.660699Z","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-10T22:25:59.66287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:25:59.663041Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:25:59.663346Z","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-10T22:25:59.663488Z","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-10T22:25:59.663509Z","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-10T22:25:59.664781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-10T22:25:59.665196Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-10T22:25:59.671734Z","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-10T22:25:59.671978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-10T22:25:59.672066Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-10T22:25:59.672092Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:25:59.672251Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:26:00.547696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:26:00.547772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:26:00.547821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-10T22:26:00.547844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:26:00.547868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-10T22:26:00.547886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-10T22:26:00.5479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-10T22:26:00.549317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:26:00.5495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:26:00.549571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:26:00.549726Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:00.550776Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:00.550912Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:00.550952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:26:00.552815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:26:00.553097Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-10T22:26:00.553118Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:26:00.553153Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:26:18.838769Z","caller":"traceutil/trace.go:171","msg":"trace[2103409037] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"216.143824ms","start":"2026-08-10T22:26:18.622584Z","end":"2026-08-10T22:26:18.838727Z","steps":["trace[2103409037] 'process raft request' (duration: 215.895798ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:18.906786Z","caller":"traceutil/trace.go:171","msg":"trace[867976748] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"180.517389ms","start":"2026-08-10T22:26:18.726235Z","end":"2026-08-10T22:26:18.906752Z","steps":["trace[867976748] 'read index received' (duration: 112.174515ms)","trace[867976748] 'applied index is now lower than readState.Index' (duration: 68.341154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:18.906928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.664142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:26:18.907045Z","caller":"traceutil/trace.go:171","msg":"trace[1895423524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"180.840257ms","start":"2026-08-10T22:26:18.726181Z","end":"2026-08-10T22:26:18.907022Z","steps":["trace[1895423524] 'agreement among raft nodes before linearized reading' (duration: 180.661892ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:24.242437Z","caller":"traceutil/trace.go:171","msg":"trace[1379673215] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"166.697366ms","start":"2026-08-10T22:26:24.075697Z","end":"2026-08-10T22:26:24.242395Z","steps":["trace[1379673215] 'process raft request' (duration: 165.865674ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:24.276218Z","caller":"traceutil/trace.go:171","msg":"trace[475525416] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"199.062265ms","start":"2026-08-10T22:26:24.0771Z","end":"2026-08-10T22:26:24.276163Z","steps":["trace[475525416] 'process raft request' (duration: 198.821699ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.264019Z","caller":"traceutil/trace.go:171","msg":"trace[1185467806] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"139.699828ms","start":"2026-08-10T22:26:36.124281Z","end":"2026-08-10T22:26:36.263981Z","steps":["trace[1185467806] 'process raft request' (duration: 83.181654ms)","trace[1185467806] 'compare' (duration: 56.37198ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:36.264817Z","caller":"traceutil/trace.go:171","msg":"trace[1759256876] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"126.185983ms","start":"2026-08-10T22:26:36.138612Z","end":"2026-08-10T22:26:36.264798Z","steps":["trace[1759256876] 'process raft request' (duration: 125.700551ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.502311Z","caller":"traceutil/trace.go:171","msg":"trace[81418769] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"163.513942ms","start":"2026-08-10T22:26:36.338769Z","end":"2026-08-10T22:26:36.502283Z","steps":["trace[81418769] 'process raft request' (duration: 161.391686ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.505563Z","caller":"traceutil/trace.go:171","msg":"trace[503305217] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"163.613845ms","start":"2026-08-10T22:26:36.341923Z","end":"2026-08-10T22:26:36.505537Z","steps":["trace[503305217] 'process raft request' (duration: 163.05134ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.50553Z","caller":"traceutil/trace.go:171","msg":"trace[1419538533] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:598; }","duration":"146.460164ms","start":"2026-08-10T22:26:36.359051Z","end":"2026-08-10T22:26:36.505511Z","steps":["trace[1419538533] 'read index received' (duration: 141.044502ms)","trace[1419538533] 'applied index is now lower than readState.Index' (duration: 5.414872ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:36.505803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.762072ms","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-10T22:26:36.505885Z","caller":"traceutil/trace.go:171","msg":"trace[18271709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"146.919536ms","start":"2026-08-10T22:26:36.358945Z","end":"2026-08-10T22:26:36.505864Z","steps":["trace[18271709] 'agreement among raft nodes before linearized reading' (duration: 146.714591ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.50595Z","caller":"traceutil/trace.go:171","msg":"trace[761855669] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"161.726515ms","start":"2026-08-10T22:26:36.344206Z","end":"2026-08-10T22:26:36.505933Z","steps":["trace[761855669] 'process raft request' (duration: 161.251663ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:36.677938Z","caller":"traceutil/trace.go:171","msg":"trace[1996859278] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"102.965263ms","start":"2026-08-10T22:26:36.574925Z","end":"2026-08-10T22:26:36.67789Z","steps":["trace[1996859278] 'process raft request' (duration: 65.699994ms)","trace[1996859278] 'compare' (duration: 36.864879ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:42.279917Z","caller":"traceutil/trace.go:171","msg":"trace[1940658744] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"114.578157ms","start":"2026-08-10T22:26:42.165312Z","end":"2026-08-10T22:26:42.27989Z","steps":["trace[1940658744] 'process raft request' (duration: 113.693674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:42.77505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.791297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-10T22:26:42.775095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.622395ms","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-10T22:26:42.775145Z","caller":"traceutil/trace.go:171","msg":"trace[1883574620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"233.941701ms","start":"2026-08-10T22:26:42.54118Z","end":"2026-08-10T22:26:42.775122Z","steps":["trace[1883574620] 'range keys from in-memory index tree' (duration: 233.550641ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:42.77519Z","caller":"traceutil/trace.go:171","msg":"trace[538163453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"211.762228ms","start":"2026-08-10T22:26:42.563393Z","end":"2026-08-10T22:26:42.775156Z","steps":["trace[538163453] 'range keys from in-memory index tree' (duration: 211.459791ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:43.377111Z","caller":"traceutil/trace.go:171","msg":"trace[254208840] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"598.104841ms","start":"2026-08-10T22:26:42.778984Z","end":"2026-08-10T22:26:43.377089Z","steps":["trace[254208840] 'process raft request' (duration: 581.564697ms)","trace[254208840] 'compare' (duration: 16.255907ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:43.377451Z","caller":"traceutil/trace.go:171","msg":"trace[21679526] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"597.900286ms","start":"2026-08-10T22:26:42.779495Z","end":"2026-08-10T22:26:43.377395Z","steps":["trace[21679526] 'process raft request' (duration: 597.521757ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:43.377576Z","caller":"traceutil/trace.go:171","msg":"trace[279229627] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"486.099691ms","start":"2026-08-10T22:26:42.891438Z","end":"2026-08-10T22:26:43.377538Z","steps":["trace[279229627] 'read index received' (duration: 469.124055ms)","trace[279229627] 'applied index is now lower than readState.Index' (duration: 16.972486ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:43.377699Z","caller":"traceutil/trace.go:171","msg":"trace[1293425191] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"454.745338ms","start":"2026-08-10T22:26:42.92293Z","end":"2026-08-10T22:26:43.377675Z","steps":["trace[1293425191] 'process raft request' (duration: 454.656495ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:43.377749Z","caller":"traceutil/trace.go:171","msg":"trace[1094784246] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"459.37257ms","start":"2026-08-10T22:26:42.91834Z","end":"2026-08-10T22:26:43.377712Z","steps":["trace[1094784246] 'process raft request' (duration: 458.952669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:43.377762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:42.77897Z","time spent":"598.207834ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:43.37787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:42.922905Z","time spent":"454.866131ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:43.377854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:42.779473Z","time spent":"598.090481ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:43.377899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.780935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-10T22:26:43.377918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:42.918298Z","time spent":"459.552794ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:26:43.377965Z","caller":"traceutil/trace.go:171","msg":"trace[533998071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"164.870577ms","start":"2026-08-10T22:26:43.213072Z","end":"2026-08-10T22:26:43.377943Z","steps":["trace[533998071] 'agreement among raft nodes before linearized reading' (duration: 164.661332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:43.378008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.515002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-444db\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-10T22:26:43.378184Z","caller":"traceutil/trace.go:171","msg":"trace[284562102] range","detail":"{range_begin:/registry/pods/kube-system/cilium-444db; range_end:; response_count:1; response_revision:639; }","duration":"486.760198ms","start":"2026-08-10T22:26:42.891393Z","end":"2026-08-10T22:26:43.378153Z","steps":["trace[284562102] 'agreement among raft nodes before linearized reading' (duration: 486.223954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:43.378255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:42.891372Z","time spent":"486.87305ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-444db\" "} {"level":"info","ts":"2026-08-10T22:26:59.202041Z","caller":"traceutil/trace.go:171","msg":"trace[347714693] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"204.738305ms","start":"2026-08-10T22:26:58.997275Z","end":"2026-08-10T22:26:59.202014Z","steps":["trace[347714693] 'process raft request' (duration: 204.588861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:13.601211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.375203ms","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-10T22:28:13.60133Z","caller":"traceutil/trace.go:171","msg":"trace[909167846] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"116.497676ms","start":"2026-08-10T22:28:13.484796Z","end":"2026-08-10T22:28:13.601293Z","steps":["trace[909167846] 'range keys from in-memory index tree' (duration: 116.163307ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:13.866897Z","caller":"traceutil/trace.go:171","msg":"trace[896089779] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"307.424156ms","start":"2026-08-10T22:28:13.55945Z","end":"2026-08-10T22:28:13.866874Z","steps":["trace[896089779] 'process raft request' (duration: 307.312733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:13.86702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:13.559429Z","time spent":"307.526929ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:13.867013Z","caller":"traceutil/trace.go:171","msg":"trace[1502726737] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1938; }","duration":"293.821059ms","start":"2026-08-10T22:28:13.573131Z","end":"2026-08-10T22:28:13.866952Z","steps":["trace[1502726737] 'read index received' (duration: 293.794368ms)","trace[1502726737] 'applied index is now lower than readState.Index' (duration: 23.101µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:13.867426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.260111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f9cb3fe2-9803-4a13-8c04-bc011abb94df\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-10T22:28:13.867537Z","caller":"traceutil/trace.go:171","msg":"trace[1051844654] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f9cb3fe2-9803-4a13-8c04-bc011abb94df; range_end:; response_count:1; response_revision:1872; }","duration":"294.438626ms","start":"2026-08-10T22:28:13.573075Z","end":"2026-08-10T22:28:13.867513Z","steps":["trace[1051844654] 'agreement among raft nodes before linearized reading' (duration: 294.003044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:13.897124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.056627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:13.89747Z","caller":"traceutil/trace.go:171","msg":"trace[236868810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"169.459857ms","start":"2026-08-10T22:28:13.727981Z","end":"2026-08-10T22:28:13.897441Z","steps":["trace[236868810] 'agreement among raft nodes before linearized reading' (duration: 169.028126ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:20.061742Z","caller":"traceutil/trace.go:171","msg":"trace[908098883] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"114.381056ms","start":"2026-08-10T22:28:19.947329Z","end":"2026-08-10T22:28:20.06171Z","steps":["trace[908098883] 'read index received' (duration: 114.110459ms)","trace[908098883] 'applied index is now lower than readState.Index' (duration: 268.917µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:28:20.061865Z","caller":"traceutil/trace.go:171","msg":"trace[971333391] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"177.780661ms","start":"2026-08-10T22:28:19.884048Z","end":"2026-08-10T22:28:20.061828Z","steps":["trace[971333391] 'process raft request' (duration: 177.485064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:20.062023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.949644ms","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-10T22:28:20.062064Z","caller":"traceutil/trace.go:171","msg":"trace[1842857785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1929; }","duration":"114.027746ms","start":"2026-08-10T22:28:19.948024Z","end":"2026-08-10T22:28:20.062052Z","steps":["trace[1842857785] 'agreement among raft nodes before linearized reading' (duration: 113.857472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:20.062092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.748705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-10T22:28:20.062146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.133254ms","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-10T22:28:20.062172Z","caller":"traceutil/trace.go:171","msg":"trace[1048322384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1929; }","duration":"114.88188ms","start":"2026-08-10T22:28:19.947267Z","end":"2026-08-10T22:28:20.062149Z","steps":["trace[1048322384] 'agreement among raft nodes before linearized reading' (duration: 114.606763ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:20.062258Z","caller":"traceutil/trace.go:171","msg":"trace[806607184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1929; }","duration":"110.297228ms","start":"2026-08-10T22:28:19.951939Z","end":"2026-08-10T22:28:20.062237Z","steps":["trace[806607184] 'agreement among raft nodes before linearized reading' (duration: 110.009931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:29:03.443719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.539675ms","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-10T22:29:03.443833Z","caller":"traceutil/trace.go:171","msg":"trace[1585385773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"137.704999ms","start":"2026-08-10T22:29:03.306107Z","end":"2026-08-10T22:29:03.443812Z","steps":["trace[1585385773] 'range keys from in-memory index tree' (duration: 137.3548ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:03.44389Z","caller":"traceutil/trace.go:171","msg":"trace[1650425476] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"109.052906ms","start":"2026-08-10T22:29:03.334757Z","end":"2026-08-10T22:29:03.44381Z","steps":["trace[1650425476] 'process raft request' (duration: 84.883922ms)","trace[1650425476] 'compare' (duration: 23.806965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:29:09.230969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.362705ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-10T22:29:09.231073Z","caller":"traceutil/trace.go:171","msg":"trace[1885019252] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"254.52556ms","start":"2026-08-10T22:29:08.976527Z","end":"2026-08-10T22:29:09.231053Z","steps":["trace[1885019252] 'read index received' (duration: 35.391µs)","trace[1885019252] 'applied index is now lower than readState.Index' (duration: 254.488269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:29:09.231212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.685274ms","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-10T22:29:09.231239Z","caller":"traceutil/trace.go:171","msg":"trace[1460641814] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2221; }","duration":"254.753255ms","start":"2026-08-10T22:29:08.976477Z","end":"2026-08-10T22:29:09.23123Z","steps":["trace[1460641814] 'agreement among raft nodes before linearized reading' (duration: 254.636252ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:09.370098Z","caller":"traceutil/trace.go:171","msg":"trace[1891394710] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2299; }","duration":"138.970113ms","start":"2026-08-10T22:29:09.231098Z","end":"2026-08-10T22:29:09.370068Z","steps":["trace[1891394710] 'read index received' (duration: 138.698486ms)","trace[1891394710] 'applied index is now lower than readState.Index' (duration: 270.527µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:29:09.370319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.207476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T22:29:09.370364Z","caller":"traceutil/trace.go:171","msg":"trace[718401452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2222; }","duration":"148.307988ms","start":"2026-08-10T22:29:09.222045Z","end":"2026-08-10T22:29:09.370353Z","steps":["trace[718401452] 'agreement among raft nodes before linearized reading' (duration: 148.127254ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:09.370251Z","caller":"traceutil/trace.go:171","msg":"trace[783780221] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"218.609165ms","start":"2026-08-10T22:29:09.151554Z","end":"2026-08-10T22:29:09.370163Z","steps":["trace[783780221] 'process raft request' (duration: 218.316868ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:17.788074Z","caller":"traceutil/trace.go:171","msg":"trace[1222968952] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"113.996031ms","start":"2026-08-10T22:30:17.674055Z","end":"2026-08-10T22:30:17.788051Z","steps":["trace[1222968952] 'read index received' (duration: 113.95553ms)","trace[1222968952] 'applied index is now lower than readState.Index' (duration: 39.741µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:30:17.788188Z","caller":"traceutil/trace.go:171","msg":"trace[1712635516] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"142.428896ms","start":"2026-08-10T22:30:17.645731Z","end":"2026-08-10T22:30:17.78816Z","steps":["trace[1712635516] 'process raft request' (duration: 142.16869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:17.788323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.246477ms","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-10T22:30:17.788376Z","caller":"traceutil/trace.go:171","msg":"trace[996591229] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2790; }","duration":"114.34983ms","start":"2026-08-10T22:30:17.674012Z","end":"2026-08-10T22:30:17.788362Z","steps":["trace[996591229] 'agreement among raft nodes before linearized reading' (duration: 114.162025ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:18.161833Z","caller":"traceutil/trace.go:171","msg":"trace[366429198] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"354.14947ms","start":"2026-08-10T22:30:17.807656Z","end":"2026-08-10T22:30:18.161805Z","steps":["trace[366429198] 'read index received' (duration: 316.209184ms)","trace[366429198] 'applied index is now lower than readState.Index' (duration: 37.938776ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:30:18.161938Z","caller":"traceutil/trace.go:171","msg":"trace[1171809766] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"356.137282ms","start":"2026-08-10T22:30:17.805778Z","end":"2026-08-10T22:30:18.161915Z","steps":["trace[1171809766] 'process raft request' (duration: 318.034373ms)","trace[1171809766] 'compare' (duration: 37.527595ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:30:18.162041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:17.805756Z","time spent":"356.231384ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:30:18.162168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.479239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7458"} {"level":"info","ts":"2026-08-10T22:30:18.162233Z","caller":"traceutil/trace.go:171","msg":"trace[913541953] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2792; }","duration":"354.630492ms","start":"2026-08-10T22:30:17.807588Z","end":"2026-08-10T22:30:18.162219Z","steps":["trace[913541953] 'agreement among raft nodes before linearized reading' (duration: 354.357695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:18.162445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:17.807564Z","time spent":"354.868419ms","remote":"127.0.0.1:43320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7482,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-08-10T22:30:18.162648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.32899ms","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-10T22:30:18.162674Z","caller":"traceutil/trace.go:171","msg":"trace[1208080787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2792; }","duration":"310.393302ms","start":"2026-08-10T22:30:17.852274Z","end":"2026-08-10T22:30:18.162667Z","steps":["trace[1208080787] 'agreement among raft nodes before linearized reading' (duration: 310.298099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:18.162695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:30:17.852254Z","time spent":"310.437773ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:31:33.689737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.70263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-10T22:31:33.689686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.185979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:31:33.689841Z","caller":"traceutil/trace.go:171","msg":"trace[844160642] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:3200; }","duration":"123.830713ms","start":"2026-08-10T22:31:33.56599Z","end":"2026-08-10T22:31:33.68982Z","steps":["trace[844160642] 'range keys from in-memory index tree' (duration: 123.516755ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:33.690176Z","caller":"traceutil/trace.go:171","msg":"trace[774821979] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:3200; }","duration":"104.462006ms","start":"2026-08-10T22:31:33.585382Z","end":"2026-08-10T22:31:33.689844Z","steps":["trace[774821979] 'count revisions from in-memory index tree' (duration: 104.081697ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:44.149803Z","caller":"traceutil/trace.go:171","msg":"trace[2099230246] linearizableReadLoop","detail":"{readStateIndex:3898; appliedIndex:3897; }","duration":"136.69993ms","start":"2026-08-10T22:32:44.013081Z","end":"2026-08-10T22:32:44.14978Z","steps":["trace[2099230246] 'read index received' (duration: 136.449184ms)","trace[2099230246] 'applied index is now lower than readState.Index' (duration: 247.766µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:44.149858Z","caller":"traceutil/trace.go:171","msg":"trace[29670633] transaction","detail":"{read_only:false; response_revision:3761; number_of_response:1; }","duration":"169.979962ms","start":"2026-08-10T22:32:43.979847Z","end":"2026-08-10T22:32:44.149827Z","steps":["trace[29670633] 'process raft request' (duration: 169.782657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:44.149955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.855264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:32:44.149987Z","caller":"traceutil/trace.go:171","msg":"trace[1762765939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3761; }","duration":"136.938316ms","start":"2026-08-10T22:32:44.013036Z","end":"2026-08-10T22:32:44.149974Z","steps":["trace[1762765939] 'agreement among raft nodes before linearized reading' (duration: 136.874705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:44.150101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.497343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:32:44.150223Z","caller":"traceutil/trace.go:171","msg":"trace[1512390217] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3761; }","duration":"118.630946ms","start":"2026-08-10T22:32:44.031547Z","end":"2026-08-10T22:32:44.150178Z","steps":["trace[1512390217] 'agreement among raft nodes before linearized reading' (duration: 118.453932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:44.150249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.746334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-08-10T22:32:44.150324Z","caller":"traceutil/trace.go:171","msg":"trace[655664399] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3761; }","duration":"117.883647ms","start":"2026-08-10T22:32:44.032424Z","end":"2026-08-10T22:32:44.150308Z","steps":["trace[655664399] 'agreement among raft nodes before linearized reading' (duration: 117.61805ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:05.529991Z","caller":"traceutil/trace.go:171","msg":"trace[353298911] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4959; }","duration":"105.137845ms","start":"2026-08-10T22:34:05.424824Z","end":"2026-08-10T22:34:05.529962Z","steps":["trace[353298911] 'read index received' (duration: 96.483308ms)","trace[353298911] 'applied index is now lower than readState.Index' (duration: 8.653297ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:05.530131Z","caller":"traceutil/trace.go:171","msg":"trace[1439152787] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"108.665657ms","start":"2026-08-10T22:34:05.42141Z","end":"2026-08-10T22:34:05.530076Z","steps":["trace[1439152787] 'process raft request' (duration: 99.827855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:05.530231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.35833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-08-10T22:34:05.53027Z","caller":"traceutil/trace.go:171","msg":"trace[2126053852] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4783; }","duration":"105.474193ms","start":"2026-08-10T22:34:05.424785Z","end":"2026-08-10T22:34:05.530259Z","steps":["trace[2126053852] 'agreement among raft nodes before linearized reading' (duration: 105.286339ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:06.761387Z","caller":"traceutil/trace.go:171","msg":"trace[1457287069] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4982; }","duration":"140.974142ms","start":"2026-08-10T22:34:06.620391Z","end":"2026-08-10T22:34:06.761365Z","steps":["trace[1457287069] 'read index received' (duration: 87.914763ms)","trace[1457287069] 'applied index is now lower than readState.Index' (duration: 53.058469ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:06.761412Z","caller":"traceutil/trace.go:171","msg":"trace[1537280150] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"166.44655ms","start":"2026-08-10T22:34:06.59494Z","end":"2026-08-10T22:34:06.761386Z","steps":["trace[1537280150] 'process raft request' (duration: 113.296917ms)","trace[1537280150] 'compare' (duration: 52.797974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:06.761569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.152298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:06.761689Z","caller":"traceutil/trace.go:171","msg":"trace[1661517075] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:4806; }","duration":"141.325563ms","start":"2026-08-10T22:34:06.620335Z","end":"2026-08-10T22:34:06.761661Z","steps":["trace[1661517075] 'agreement among raft nodes before linearized reading' (duration: 141.140447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:06.779793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.464611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-jmtfx\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-08-10T22:34:06.779886Z","caller":"traceutil/trace.go:171","msg":"trace[599234036] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-jmtfx; range_end:; response_count:1; response_revision:4808; }","duration":"155.588925ms","start":"2026-08-10T22:34:06.624273Z","end":"2026-08-10T22:34:06.779862Z","steps":["trace[599234036] 'agreement among raft nodes before linearized reading' (duration: 155.4126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.683744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.001533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:34:09.683809Z","caller":"traceutil/trace.go:171","msg":"trace[836814915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4950; }","duration":"254.124536ms","start":"2026-08-10T22:34:09.429672Z","end":"2026-08-10T22:34:09.683796Z","steps":["trace[836814915] 'range keys from in-memory index tree' (duration: 253.762167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.683906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.813691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:09.683944Z","caller":"traceutil/trace.go:171","msg":"trace[490701333] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5131; }","duration":"101.082977ms","start":"2026-08-10T22:34:09.582836Z","end":"2026-08-10T22:34:09.683919Z","steps":["trace[490701333] 'read index received' (duration: 87.275096ms)","trace[490701333] 'applied index is now lower than readState.Index' (duration: 13.806681ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:09.68397Z","caller":"traceutil/trace.go:171","msg":"trace[1052162327] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:4950; }","duration":"302.921414ms","start":"2026-08-10T22:34:09.381036Z","end":"2026-08-10T22:34:09.683957Z","steps":["trace[1052162327] 'count revisions from in-memory index tree' (duration: 302.725459ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.683974Z","caller":"traceutil/trace.go:171","msg":"trace[1928115419] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"132.574923ms","start":"2026-08-10T22:34:09.551368Z","end":"2026-08-10T22:34:09.683943Z","steps":["trace[1928115419] 'process raft request' (duration: 118.857324ms)","trace[1928115419] 'compare' (duration: 13.349889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:09.684004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:09.381013Z","time spent":"302.980845ms","remote":"127.0.0.1:49974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-10T22:34:09.684029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.19169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:34:09.684056Z","caller":"traceutil/trace.go:171","msg":"trace[847899669] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4951; }","duration":"101.254802ms","start":"2026-08-10T22:34:09.582791Z","end":"2026-08-10T22:34:09.684046Z","steps":["trace[847899669] 'agreement among raft nodes before linearized reading' (duration: 101.18726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.6841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.537224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cc52260-849d-49a5-8f33-a7286626481c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-10T22:34:09.684217Z","caller":"traceutil/trace.go:171","msg":"trace[1959972198] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cc52260-849d-49a5-8f33-a7286626481c; range_end:; response_count:1; response_revision:4950; }","duration":"226.721708ms","start":"2026-08-10T22:34:09.457464Z","end":"2026-08-10T22:34:09.684186Z","steps":["trace[1959972198] 'range keys from in-memory index tree' (duration: 226.274107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.683689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.021355ms","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-10T22:34:09.684364Z","caller":"traceutil/trace.go:171","msg":"trace[1471874385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4950; }","duration":"141.740942ms","start":"2026-08-10T22:34:09.542611Z","end":"2026-08-10T22:34:09.684352Z","steps":["trace[1471874385] 'range keys from in-memory index tree' (duration: 140.86392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.684381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.14344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787128"} {"level":"info","ts":"2026-08-10T22:34:09.684453Z","caller":"traceutil/trace.go:171","msg":"trace[2039041698] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4950; }","duration":"295.249614ms","start":"2026-08-10T22:34:09.389185Z","end":"2026-08-10T22:34:09.684435Z","steps":["trace[2039041698] 'range keys from in-memory index tree' (duration: 294.664328ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.80003Z","caller":"traceutil/trace.go:171","msg":"trace[790489285] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"111.272985ms","start":"2026-08-10T22:34:09.688723Z","end":"2026-08-10T22:34:09.799996Z","steps":["trace[790489285] 'process raft request' (duration: 97.462243ms)","trace[790489285] 'compare' (duration: 13.518165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:11.429251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.417223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:11.429346Z","caller":"traceutil/trace.go:171","msg":"trace[1591669627] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:0; response_revision:4970; }","duration":"123.555226ms","start":"2026-08-10T22:34:11.305768Z","end":"2026-08-10T22:34:11.429323Z","steps":["trace[1591669627] 'range keys from in-memory index tree' (duration: 123.284579ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:15.686648Z","caller":"traceutil/trace.go:171","msg":"trace[1990058463] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"108.08901ms","start":"2026-08-10T22:34:15.578522Z","end":"2026-08-10T22:34:15.686611Z","steps":["trace[1990058463] 'process raft request' (duration: 107.870634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:21.978187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.587395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:21.978484Z","caller":"traceutil/trace.go:171","msg":"trace[15714401] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"279.370567ms","start":"2026-08-10T22:34:21.699079Z","end":"2026-08-10T22:34:21.97845Z","steps":["trace[15714401] 'process raft request' (duration: 52.440343ms)","trace[15714401] 'compare' (duration: 226.380019ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:21.978538Z","caller":"traceutil/trace.go:171","msg":"trace[695981425] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5362; }","duration":"175.037394ms","start":"2026-08-10T22:34:21.803479Z","end":"2026-08-10T22:34:21.978516Z","steps":["trace[695981425] 'read index received' (duration: 44.421µs)","trace[695981425] 'applied index is now lower than readState.Index' (duration: 174.991623ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:21.978562Z","caller":"traceutil/trace.go:171","msg":"trace[298528825] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"215.810173ms","start":"2026-08-10T22:34:21.762721Z","end":"2026-08-10T22:34:21.978531Z","steps":["trace[298528825] 'process raft request' (duration: 215.641828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:21.978842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.65435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:21.978947Z","caller":"traceutil/trace.go:171","msg":"trace[2108963941] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5178; }","duration":"170.819404ms","start":"2026-08-10T22:34:21.808101Z","end":"2026-08-10T22:34:21.978921Z","steps":["trace[2108963941] 'agreement among raft nodes before linearized reading' (duration: 170.624829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:21.979068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.439245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:34:21.979126Z","caller":"traceutil/trace.go:171","msg":"trace[1287022169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5178; }","duration":"133.554939ms","start":"2026-08-10T22:34:21.845553Z","end":"2026-08-10T22:34:21.979108Z","steps":["trace[1287022169] 'agreement among raft nodes before linearized reading' (duration: 133.379814ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.979076Z","caller":"traceutil/trace.go:171","msg":"trace[662179539] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"142.833401ms","start":"2026-08-10T22:34:21.836217Z","end":"2026-08-10T22:34:21.97905Z","steps":["trace[662179539] 'process raft request' (duration: 142.244595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:21.979349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.867016ms","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-10T22:34:21.979421Z","caller":"traceutil/trace.go:171","msg":"trace[699051769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5178; }","duration":"175.938058ms","start":"2026-08-10T22:34:21.803464Z","end":"2026-08-10T22:34:21.979402Z","steps":["trace[699051769] 'agreement among raft nodes before linearized reading' (duration: 175.167938ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.114858Z","caller":"traceutil/trace.go:171","msg":"trace[1503986307] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"132.608473ms","start":"2026-08-10T22:34:21.982228Z","end":"2026-08-10T22:34:22.114837Z","steps":["trace[1503986307] 'process raft request' (duration: 130.145429ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.121585Z","caller":"traceutil/trace.go:171","msg":"trace[355350411] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"139.233237ms","start":"2026-08-10T22:34:21.982331Z","end":"2026-08-10T22:34:22.121564Z","steps":["trace[355350411] 'process raft request' (duration: 138.98581ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.122057Z","caller":"traceutil/trace.go:171","msg":"trace[1477944191] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"138.881028ms","start":"2026-08-10T22:34:21.983157Z","end":"2026-08-10T22:34:22.122039Z","steps":["trace[1477944191] 'process raft request' (duration: 138.337394ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.122123Z","caller":"traceutil/trace.go:171","msg":"trace[839570302] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"138.695983ms","start":"2026-08-10T22:34:21.98341Z","end":"2026-08-10T22:34:22.122106Z","steps":["trace[839570302] 'process raft request' (duration: 138.391015ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.122236Z","caller":"traceutil/trace.go:171","msg":"trace[782546306] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"138.166469ms","start":"2026-08-10T22:34:21.984021Z","end":"2026-08-10T22:34:22.122187Z","steps":["trace[782546306] 'process raft request' (duration: 138.018315ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.124829Z","caller":"traceutil/trace.go:171","msg":"trace[709196081] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"140.736237ms","start":"2026-08-10T22:34:21.984073Z","end":"2026-08-10T22:34:22.124809Z","steps":["trace[709196081] 'process raft request' (duration: 140.576853ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:22.124905Z","caller":"traceutil/trace.go:171","msg":"trace[1204393139] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"140.376366ms","start":"2026-08-10T22:34:21.984514Z","end":"2026-08-10T22:34:22.12489Z","steps":["trace[1204393139] 'process raft request' (duration: 140.255843ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:30.604051Z","caller":"traceutil/trace.go:171","msg":"trace[1380668110] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"340.776736ms","start":"2026-08-10T22:34:30.263249Z","end":"2026-08-10T22:34:30.604026Z","steps":["trace[1380668110] 'process raft request' (duration: 340.617961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:30.604208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.263233Z","time spent":"340.897439ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:30.729037Z","caller":"traceutil/trace.go:171","msg":"trace[1239177162] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"465.492292ms","start":"2026-08-10T22:34:30.263517Z","end":"2026-08-10T22:34:30.729009Z","steps":["trace[1239177162] 'process raft request' (duration: 377.775215ms)","trace[1239177162] 'compare' (duration: 87.4298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:30.729213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.263498Z","time spent":"465.606165ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:30.72926Z","caller":"traceutil/trace.go:171","msg":"trace[1754211500] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"465.262576ms","start":"2026-08-10T22:34:30.263977Z","end":"2026-08-10T22:34:30.729239Z","steps":["trace[1754211500] 'process raft request' (duration: 464.952088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:30.7294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.263951Z","time spent":"465.380239ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:30.729471Z","caller":"traceutil/trace.go:171","msg":"trace[1236963192] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"465.132573ms","start":"2026-08-10T22:34:30.264322Z","end":"2026-08-10T22:34:30.729455Z","steps":["trace[1236963192] 'process raft request' (duration: 464.852486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:30.729602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.264314Z","time spent":"465.242136ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:30.729679Z","caller":"traceutil/trace.go:171","msg":"trace[1796858846] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5534; }","duration":"341.494194ms","start":"2026-08-10T22:34:30.38817Z","end":"2026-08-10T22:34:30.729664Z","steps":["trace[1796858846] 'read index received' (duration: 215.778612ms)","trace[1796858846] 'applied index is now lower than readState.Index' (duration: 125.714182ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:30.729818Z","caller":"traceutil/trace.go:171","msg":"trace[622060498] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"351.371623ms","start":"2026-08-10T22:34:30.378406Z","end":"2026-08-10T22:34:30.729778Z","steps":["trace[622060498] 'process raft request' (duration: 350.998984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:30.729944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.758701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae140f37-3c88-4624-9642-7eab0e7ccfc1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-10T22:34:30.730027Z","caller":"traceutil/trace.go:171","msg":"trace[1446750759] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ae140f37-3c88-4624-9642-7eab0e7ccfc1; range_end:; response_count:1; response_revision:5350; }","duration":"341.886534ms","start":"2026-08-10T22:34:30.388122Z","end":"2026-08-10T22:34:30.730008Z","steps":["trace[1446750759] 'agreement among raft nodes before linearized reading' (duration: 341.651108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:30.730035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.378381Z","time spent":"351.564478ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:30.730078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:30.388109Z","time spent":"341.959747ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae140f37-3c88-4624-9642-7eab0e7ccfc1\" "} {"level":"info","ts":"2026-08-10T22:34:41.672785Z","caller":"traceutil/trace.go:171","msg":"trace[147378502] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"153.48143ms","start":"2026-08-10T22:34:41.519261Z","end":"2026-08-10T22:34:41.672743Z","steps":["trace[147378502] 'process raft request' (duration: 153.226323ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:42.043798Z","caller":"traceutil/trace.go:171","msg":"trace[485449106] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"363.952562ms","start":"2026-08-10T22:34:41.679816Z","end":"2026-08-10T22:34:42.043768Z","steps":["trace[485449106] 'process raft request' (duration: 363.87098ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:42.043859Z","caller":"traceutil/trace.go:171","msg":"trace[1021879380] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"364.021555ms","start":"2026-08-10T22:34:41.679802Z","end":"2026-08-10T22:34:42.043823Z","steps":["trace[1021879380] 'process raft request' (duration: 305.02838ms)","trace[1021879380] 'compare' (duration: 58.447221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:42.043961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.6798Z","time spent":"364.078586ms","remote":"127.0.0.1:43004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.044039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.67978Z","time spent":"364.165428ms","remote":"127.0.0.1:43004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:42.099583Z","caller":"traceutil/trace.go:171","msg":"trace[652936301] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"419.420066ms","start":"2026-08-10T22:34:41.680141Z","end":"2026-08-10T22:34:42.099561Z","steps":["trace[652936301] 'process raft request' (duration: 419.231761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.099684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.680119Z","time spent":"419.518548ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:42.099801Z","caller":"traceutil/trace.go:171","msg":"trace[1958278085] linearizableReadLoop","detail":"{readStateIndex:5713; appliedIndex:5709; }","duration":"373.458451ms","start":"2026-08-10T22:34:41.726317Z","end":"2026-08-10T22:34:42.099775Z","steps":["trace[1958278085] 'read index received' (duration: 258.532921ms)","trace[1958278085] 'applied index is now lower than readState.Index' (duration: 114.92362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:42.099986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.662687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:42.100056Z","caller":"traceutil/trace.go:171","msg":"trace[1049956179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5519; }","duration":"373.79052ms","start":"2026-08-10T22:34:41.726249Z","end":"2026-08-10T22:34:42.100039Z","steps":["trace[1049956179] 'agreement among raft nodes before linearized reading' (duration: 373.676107ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:42.099918Z","caller":"traceutil/trace.go:171","msg":"trace[1698541252] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"419.095516ms","start":"2026-08-10T22:34:41.680758Z","end":"2026-08-10T22:34:42.099853Z","steps":["trace[1698541252] 'process raft request' (duration: 418.743637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.100096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.726225Z","time spent":"373.863111ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T22:34:42.100328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.680732Z","time spent":"419.379555ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.342112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.772736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:42.342353Z","caller":"traceutil/trace.go:171","msg":"trace[1812115828] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"214.042697ms","start":"2026-08-10T22:34:42.128289Z","end":"2026-08-10T22:34:42.342332Z","steps":["trace[1812115828] 'process raft request' (duration: 90.963833ms)","trace[1812115828] 'compare' (duration: 122.656533ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:42.400877Z","caller":"traceutil/trace.go:171","msg":"trace[1108533547] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"264.110778ms","start":"2026-08-10T22:34:42.136749Z","end":"2026-08-10T22:34:42.40086Z","steps":["trace[1108533547] 'process raft request' (duration: 263.611134ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:36:00.574775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3026} {"level":"info","ts":"2026-08-10T22:36:00.661236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3026,"took":"84.465592ms","hash":2379022401,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T22:36:00.66134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2379022401,"revision":3026,"compact-revision":-1} {"level":"info","ts":"2026-08-10T22:40:36.924323Z","caller":"traceutil/trace.go:171","msg":"trace[641496485] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"347.684318ms","start":"2026-08-10T22:40:36.576616Z","end":"2026-08-10T22:40:36.924301Z","steps":["trace[641496485] 'process raft request' (duration: 347.545075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:36.924471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:36.576601Z","time spent":"347.798332ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:37.166508Z","caller":"traceutil/trace.go:171","msg":"trace[1961503316] linearizableReadLoop","detail":"{readStateIndex:8476; appliedIndex:8474; }","duration":"587.224926ms","start":"2026-08-10T22:40:36.579227Z","end":"2026-08-10T22:40:37.166452Z","steps":["trace[1961503316] 'read index received' (duration: 344.959147ms)","trace[1961503316] 'applied index is now lower than readState.Index' (duration: 242.264549ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:37.166601Z","caller":"traceutil/trace.go:171","msg":"trace[855376640] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"589.688109ms","start":"2026-08-10T22:40:36.576844Z","end":"2026-08-10T22:40:37.166532Z","steps":["trace[855376640] 'process raft request' (duration: 589.33942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:37.166758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.875836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:37.166807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:36.57683Z","time spent":"589.860265ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:37.166848Z","caller":"traceutil/trace.go:171","msg":"trace[1643593247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8197; }","duration":"441.002849ms","start":"2026-08-10T22:40:36.725828Z","end":"2026-08-10T22:40:37.166831Z","steps":["trace[1643593247] 'agreement among raft nodes before linearized reading' (duration: 440.860966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:37.166901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:36.72581Z","time spent":"441.083132ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T22:40:37.16684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.587146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:40:37.167101Z","caller":"traceutil/trace.go:171","msg":"trace[112898738] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"533.382057ms","start":"2026-08-10T22:40:36.633176Z","end":"2026-08-10T22:40:37.166558Z","steps":["trace[112898738] 'process raft request' (duration: 533.202803ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:37.16713Z","caller":"traceutil/trace.go:171","msg":"trace[86973825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8197; }","duration":"587.838702ms","start":"2026-08-10T22:40:36.579209Z","end":"2026-08-10T22:40:37.167048Z","steps":["trace[86973825] 'agreement among raft nodes before linearized reading' (duration: 587.37789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:37.167183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:36.579203Z","time spent":"587.965815ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:40:37.167198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:36.633148Z","time spent":"533.987082ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:48.26191Z","caller":"traceutil/trace.go:171","msg":"trace[2031012071] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"127.524128ms","start":"2026-08-10T22:40:48.134362Z","end":"2026-08-10T22:40:48.261886Z","steps":["trace[2031012071] 'process raft request' (duration: 127.394274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:48.54496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.509728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:40:48.545225Z","caller":"traceutil/trace.go:171","msg":"trace[1878084174] linearizableReadLoop","detail":"{readStateIndex:8538; appliedIndex:8536; }","duration":"257.078347ms","start":"2026-08-10T22:40:48.288118Z","end":"2026-08-10T22:40:48.545196Z","steps":["trace[1878084174] 'read index received' (duration: 51.25186ms)","trace[1878084174] 'applied index is now lower than readState.Index' (duration: 205.825437ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:48.5453Z","caller":"traceutil/trace.go:171","msg":"trace[1388339885] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"272.069969ms","start":"2026-08-10T22:40:48.2732Z","end":"2026-08-10T22:40:48.54527Z","steps":["trace[1388339885] 'process raft request' (duration: 271.887484ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:48.545321Z","caller":"traceutil/trace.go:171","msg":"trace[1698084390] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"405.285855ms","start":"2026-08-10T22:40:48.139979Z","end":"2026-08-10T22:40:48.545265Z","steps":["trace[1698084390] 'process raft request' (duration: 199.375147ms)","trace[1698084390] 'compare' (duration: 205.235241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:40:48.545426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.962887ms","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-10T22:40:48.545465Z","caller":"traceutil/trace.go:171","msg":"trace[1973125359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8255; }","duration":"157.026799ms","start":"2026-08-10T22:40:48.388428Z","end":"2026-08-10T22:40:48.545455Z","steps":["trace[1973125359] 'agreement among raft nodes before linearized reading' (duration: 156.889896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:48.545563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:48.139949Z","time spent":"405.47006ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:40:48.545567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.411836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-10T22:40:48.545686Z","caller":"traceutil/trace.go:171","msg":"trace[1332193374] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8255; }","duration":"257.590201ms","start":"2026-08-10T22:40:48.28807Z","end":"2026-08-10T22:40:48.54566Z","steps":["trace[1332193374] 'agreement among raft nodes before linearized reading' (duration: 257.230721ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:49.552646Z","caller":"traceutil/trace.go:171","msg":"trace[1171747759] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"320.076305ms","start":"2026-08-10T22:40:49.232532Z","end":"2026-08-10T22:40:49.552609Z","steps":["trace[1171747759] 'process raft request' (duration: 319.841929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:49.552896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:49.232515Z","time spent":"320.247951ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:49.571568Z","caller":"traceutil/trace.go:171","msg":"trace[521724451] linearizableReadLoop","detail":"{readStateIndex:8544; appliedIndex:8542; }","duration":"239.078055ms","start":"2026-08-10T22:40:49.332471Z","end":"2026-08-10T22:40:49.571549Z","steps":["trace[521724451] 'read index received' (duration: 219.879943ms)","trace[521724451] 'applied index is now lower than readState.Index' (duration: 19.196682ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:49.571651Z","caller":"traceutil/trace.go:171","msg":"trace[1029495373] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"338.24224ms","start":"2026-08-10T22:40:49.233371Z","end":"2026-08-10T22:40:49.571613Z","steps":["trace[1029495373] 'process raft request' (duration: 338.070686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:49.571763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.27458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mzwrt\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-10T22:40:49.571798Z","caller":"traceutil/trace.go:171","msg":"trace[1131131001] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mzwrt; range_end:; response_count:1; response_revision:8260; }","duration":"239.351053ms","start":"2026-08-10T22:40:49.332437Z","end":"2026-08-10T22:40:49.571788Z","steps":["trace[1131131001] 'agreement among raft nodes before linearized reading' (duration: 239.187288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:49.571839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:49.233352Z","time spent":"338.370273ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:50.467685Z","caller":"traceutil/trace.go:171","msg":"trace[126909422] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"198.734741ms","start":"2026-08-10T22:40:50.268925Z","end":"2026-08-10T22:40:50.46766Z","steps":["trace[126909422] 'process raft request' (duration: 198.581997ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:00.580258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6149} {"level":"info","ts":"2026-08-10T22:41:00.672357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6149,"took":"90.050146ms","hash":1201098769,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":23384064,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-10T22:41:00.672422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1201098769,"revision":6149,"compact-revision":3026} {"level":"info","ts":"2026-08-10T22:41:17.808254Z","caller":"traceutil/trace.go:171","msg":"trace[1119164383] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"614.495054ms","start":"2026-08-10T22:41:17.19373Z","end":"2026-08-10T22:41:17.808225Z","steps":["trace[1119164383] 'process raft request' (duration: 614.040223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:17.80843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:17.193709Z","time spent":"614.630697ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:41:21.935488Z","caller":"traceutil/trace.go:171","msg":"trace[832375951] linearizableReadLoop","detail":"{readStateIndex:8781; appliedIndex:8780; }","duration":"109.705316ms","start":"2026-08-10T22:41:21.825761Z","end":"2026-08-10T22:41:21.935467Z","steps":["trace[832375951] 'read index received' (duration: 109.569813ms)","trace[832375951] 'applied index is now lower than readState.Index' (duration: 134.343µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:21.935519Z","caller":"traceutil/trace.go:171","msg":"trace[855400107] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"110.842255ms","start":"2026-08-10T22:41:21.824654Z","end":"2026-08-10T22:41:21.935497Z","steps":["trace[855400107] 'process raft request' (duration: 110.675821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:21.935697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.930341ms","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-10T22:41:21.935739Z","caller":"traceutil/trace.go:171","msg":"trace[1454941792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8488; }","duration":"109.986523ms","start":"2026-08-10T22:41:21.825741Z","end":"2026-08-10T22:41:21.935728Z","steps":["trace[1454941792] 'agreement among raft nodes before linearized reading' (duration: 109.852599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.211005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.750595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:41:22.211213Z","caller":"traceutil/trace.go:171","msg":"trace[1123288284] linearizableReadLoop","detail":"{readStateIndex:8783; appliedIndex:8781; }","duration":"275.641791ms","start":"2026-08-10T22:41:21.935553Z","end":"2026-08-10T22:41:22.211195Z","steps":["trace[1123288284] 'read index received' (duration: 167.683061ms)","trace[1123288284] 'applied index is now lower than readState.Index' (duration: 107.95776ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:22.211264Z","caller":"traceutil/trace.go:171","msg":"trace[27762153] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"294.264169ms","start":"2026-08-10T22:41:21.916973Z","end":"2026-08-10T22:41:22.211237Z","steps":["trace[27762153] 'process raft request' (duration: 294.144375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.21136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.691312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:41:22.211386Z","caller":"traceutil/trace.go:171","msg":"trace[1828568624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8490; }","duration":"381.729083ms","start":"2026-08-10T22:41:21.829648Z","end":"2026-08-10T22:41:22.211377Z","steps":["trace[1828568624] 'agreement among raft nodes before linearized reading' (duration: 381.59992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.211413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:21.829642Z","time spent":"381.764154ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:41:22.211446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.278009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:41:22.211431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.089581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:41:22.211512Z","caller":"traceutil/trace.go:171","msg":"trace[636029501] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8490; }","duration":"291.375711ms","start":"2026-08-10T22:41:21.92012Z","end":"2026-08-10T22:41:22.211495Z","steps":["trace[636029501] 'agreement among raft nodes before linearized reading' (duration: 291.264758ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:22.211531Z","caller":"traceutil/trace.go:171","msg":"trace[770503420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8490; }","duration":"279.241405ms","start":"2026-08-10T22:41:21.932267Z","end":"2026-08-10T22:41:22.211509Z","steps":["trace[770503420] 'agreement among raft nodes before linearized reading' (duration: 279.091321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.21156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.529639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mzwrt\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-10T22:41:22.211656Z","caller":"traceutil/trace.go:171","msg":"trace[763813419] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mzwrt; range_end:; response_count:1; response_revision:8490; }","duration":"378.653063ms","start":"2026-08-10T22:41:21.83298Z","end":"2026-08-10T22:41:22.211633Z","steps":["trace[763813419] 'agreement among raft nodes before linearized reading' (duration: 378.396776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.211703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:21.832967Z","time spent":"378.723044ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-mzwrt\" "} {"level":"info","ts":"2026-08-10T22:41:22.211973Z","caller":"traceutil/trace.go:171","msg":"trace[1185308452] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"337.310792ms","start":"2026-08-10T22:41:21.874616Z","end":"2026-08-10T22:41:22.211927Z","steps":["trace[1185308452] 'process raft request' (duration: 228.533801ms)","trace[1185308452] 'compare' (duration: 107.481887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:22.212199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:21.87459Z","time spent":"337.510467ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:41:22.336225Z","caller":"traceutil/trace.go:171","msg":"trace[1765698893] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"396.514448ms","start":"2026-08-10T22:41:21.939677Z","end":"2026-08-10T22:41:22.336191Z","steps":["trace[1765698893] 'process raft request' (duration: 377.623445ms)","trace[1765698893] 'compare' (duration: 18.74745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:22.336397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:21.939657Z","time spent":"396.657041ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:41:22.34052Z","caller":"traceutil/trace.go:171","msg":"trace[1840345289] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8783; }","duration":"123.256309ms","start":"2026-08-10T22:41:22.217248Z","end":"2026-08-10T22:41:22.340505Z","steps":["trace[1840345289] 'read index received' (duration: 100.133585ms)","trace[1840345289] 'applied index is now lower than readState.Index' (duration: 23.121584ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:22.340599Z","caller":"traceutil/trace.go:171","msg":"trace[1497054165] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"124.163134ms","start":"2026-08-10T22:41:22.216408Z","end":"2026-08-10T22:41:22.340571Z","steps":["trace[1497054165] 'process raft request' (duration: 124.00996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:22.340818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.576238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228865"} {"level":"info","ts":"2026-08-10T22:41:22.340847Z","caller":"traceutil/trace.go:171","msg":"trace[1280521348] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8492; }","duration":"123.643ms","start":"2026-08-10T22:41:22.217197Z","end":"2026-08-10T22:41:22.34084Z","steps":["trace[1280521348] 'agreement among raft nodes before linearized reading' (duration: 123.362413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:30.231632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.799857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:41:30.231799Z","caller":"traceutil/trace.go:171","msg":"trace[6040179] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"259.236572ms","start":"2026-08-10T22:41:29.972543Z","end":"2026-08-10T22:41:30.231779Z","steps":["trace[6040179] 'process raft request' (duration: 110.161817ms)","trace[6040179] 'compare' (duration: 148.581841ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:30.401921Z","caller":"traceutil/trace.go:171","msg":"trace[84785859] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"164.808405ms","start":"2026-08-10T22:41:30.237072Z","end":"2026-08-10T22:41:30.40188Z","steps":["trace[84785859] 'process raft request' (duration: 154.228159ms)","trace[84785859] 'compare' (duration: 10.229157ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:31.031953Z","caller":"traceutil/trace.go:171","msg":"trace[1071824064] linearizableReadLoop","detail":"{readStateIndex:8880; appliedIndex:8879; }","duration":"118.868495ms","start":"2026-08-10T22:41:30.913062Z","end":"2026-08-10T22:41:31.031931Z","steps":["trace[1071824064] 'read index received' (duration: 68.830698ms)","trace[1071824064] 'applied index is now lower than readState.Index' (duration: 50.036697ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:31.032046Z","caller":"traceutil/trace.go:171","msg":"trace[865798367] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"135.017186ms","start":"2026-08-10T22:41:30.896999Z","end":"2026-08-10T22:41:31.032016Z","steps":["trace[865798367] 'process raft request' (duration: 84.925408ms)","trace[865798367] 'compare' (duration: 49.901204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:31.032118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.033719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:41:31.032157Z","caller":"traceutil/trace.go:171","msg":"trace[1262359780] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8582; }","duration":"119.134242ms","start":"2026-08-10T22:41:30.913011Z","end":"2026-08-10T22:41:31.032145Z","steps":["trace[1262359780] 'agreement among raft nodes before linearized reading' (duration: 119.04884ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:31.489095Z","caller":"traceutil/trace.go:171","msg":"trace[582005537] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"166.067798ms","start":"2026-08-10T22:41:31.323001Z","end":"2026-08-10T22:41:31.489069Z","steps":["trace[582005537] 'process raft request' (duration: 165.921284ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:49.192324Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000235d.wal"} {"level":"info","ts":"2026-08-10T22:43:11.137992Z","caller":"traceutil/trace.go:171","msg":"trace[2020423556] linearizableReadLoop","detail":"{readStateIndex:9780; appliedIndex:9779; }","duration":"301.023264ms","start":"2026-08-10T22:43:10.836927Z","end":"2026-08-10T22:43:11.13795Z","steps":["trace[2020423556] 'read index received' (duration: 300.733717ms)","trace[2020423556] 'applied index is now lower than readState.Index' (duration: 287.737µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:43:11.138283Z","caller":"traceutil/trace.go:171","msg":"trace[811528894] transaction","detail":"{read_only:false; response_revision:9456; number_of_response:1; }","duration":"316.55417ms","start":"2026-08-10T22:43:10.821623Z","end":"2026-08-10T22:43:11.138177Z","steps":["trace[811528894] 'process raft request' (duration: 316.146849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:11.138367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.407122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-10T22:43:11.138299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.276811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-10T22:43:11.138426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.976239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:43:11.138467Z","caller":"traceutil/trace.go:171","msg":"trace[2088099264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9456; }","duration":"260.546206ms","start":"2026-08-10T22:43:10.877904Z","end":"2026-08-10T22:43:11.13845Z","steps":["trace[2088099264] 'agreement among raft nodes before linearized reading' (duration: 260.364721ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:11.138522Z","caller":"traceutil/trace.go:171","msg":"trace[1994701100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9456; }","duration":"258.105942ms","start":"2026-08-10T22:43:10.880398Z","end":"2026-08-10T22:43:11.138504Z","steps":["trace[1994701100] 'agreement among raft nodes before linearized reading' (duration: 257.974589ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:11.138531Z","caller":"traceutil/trace.go:171","msg":"trace[1672353786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9456; }","duration":"301.6304ms","start":"2026-08-10T22:43:10.836868Z","end":"2026-08-10T22:43:11.138499Z","steps":["trace[1672353786] 'agreement among raft nodes before linearized reading' (duration: 301.272221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:11.138636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:43:10.836841Z","time spent":"301.763353ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T22:43:11.138618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:43:10.821602Z","time spent":"316.799686ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:43:11.357158Z","caller":"traceutil/trace.go:171","msg":"trace[217639764] linearizableReadLoop","detail":"{readStateIndex:9785; appliedIndex:9784; }","duration":"162.501925ms","start":"2026-08-10T22:43:11.194623Z","end":"2026-08-10T22:43:11.357125Z","steps":["trace[217639764] 'read index received' (duration: 162.291759ms)","trace[217639764] 'applied index is now lower than readState.Index' (duration: 209.006µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:43:11.357261Z","caller":"traceutil/trace.go:171","msg":"trace[1961046475] transaction","detail":"{read_only:false; response_revision:9461; number_of_response:1; }","duration":"185.927256ms","start":"2026-08-10T22:43:11.171303Z","end":"2026-08-10T22:43:11.35723Z","steps":["trace[1961046475] 'process raft request' (duration: 185.630759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:11.357377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.739382ms","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-10T22:43:11.357413Z","caller":"traceutil/trace.go:171","msg":"trace[1623999990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9461; }","duration":"162.823793ms","start":"2026-08-10T22:43:11.194578Z","end":"2026-08-10T22:43:11.357402Z","steps":["trace[1623999990] 'agreement among raft nodes before linearized reading' (duration: 162.673369ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:11.381593Z","caller":"traceutil/trace.go:171","msg":"trace[1121840544] transaction","detail":"{read_only:false; response_revision:9462; number_of_response:1; }","duration":"111.176585ms","start":"2026-08-10T22:43:11.270388Z","end":"2026-08-10T22:43:11.381564Z","steps":["trace[1121840544] 'process raft request' (duration: 110.98256ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:41.497695Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:43:41.503615Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:43:41.503772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:46:00.603229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8324} {"level":"info","ts":"2026-08-10T22:46:00.70959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8324,"took":"103.101584ms","hash":3298625433,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":24150016,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-10T22:46:00.709671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3298625433,"revision":8324,"compact-revision":6149} {"level":"info","ts":"2026-08-10T22:48:06.945108Z","caller":"traceutil/trace.go:171","msg":"trace[1987363966] transaction","detail":"{read_only:false; response_revision:11659; number_of_response:1; }","duration":"123.308373ms","start":"2026-08-10T22:48:06.821777Z","end":"2026-08-10T22:48:06.945086Z","steps":["trace[1987363966] 'process raft request' (duration: 123.267342ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:48:06.945246Z","caller":"traceutil/trace.go:171","msg":"trace[408588929] transaction","detail":"{read_only:false; response_revision:11657; number_of_response:1; }","duration":"126.776472ms","start":"2026-08-10T22:48:06.818455Z","end":"2026-08-10T22:48:06.945231Z","steps":["trace[408588929] 'process raft request' (duration: 73.419968ms)","trace[408588929] 'compare' (duration: 53.010265ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:48:06.945333Z","caller":"traceutil/trace.go:171","msg":"trace[1034781580] transaction","detail":"{read_only:false; response_revision:11658; number_of_response:1; }","duration":"124.725479ms","start":"2026-08-10T22:48:06.820574Z","end":"2026-08-10T22:48:06.9453Z","steps":["trace[1034781580] 'process raft request' (duration: 124.440652ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:48:24.168038Z","caller":"traceutil/trace.go:171","msg":"trace[1462705851] transaction","detail":"{read_only:false; response_revision:11752; number_of_response:1; }","duration":"142.679178ms","start":"2026-08-10T22:48:24.025332Z","end":"2026-08-10T22:48:24.168011Z","steps":["trace[1462705851] 'process raft request' (duration: 142.506993ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:48:24.711105Z","caller":"traceutil/trace.go:171","msg":"trace[1321665648] transaction","detail":"{read_only:false; response_revision:11757; number_of_response:1; }","duration":"102.499478ms","start":"2026-08-10T22:48:24.608581Z","end":"2026-08-10T22:48:24.711081Z","steps":["trace[1321665648] 'process raft request' (duration: 102.381165ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:48:24.711096Z","caller":"traceutil/trace.go:171","msg":"trace[1546248153] transaction","detail":"{read_only:false; response_revision:11756; number_of_response:1; }","duration":"103.967607ms","start":"2026-08-10T22:48:24.607092Z","end":"2026-08-10T22:48:24.711059Z","steps":["trace[1546248153] 'process raft request' (duration: 66.921889ms)","trace[1546248153] 'compare' (duration: 36.765031ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:48:25.154873Z","caller":"traceutil/trace.go:171","msg":"trace[1446571956] linearizableReadLoop","detail":"{readStateIndex:12156; appliedIndex:12155; }","duration":"121.34627ms","start":"2026-08-10T22:48:25.033491Z","end":"2026-08-10T22:48:25.154837Z","steps":["trace[1446571956] 'read index received' (duration: 121.062983ms)","trace[1446571956] 'applied index is now lower than readState.Index' (duration: 281.827µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:48:25.154932Z","caller":"traceutil/trace.go:171","msg":"trace[342660542] transaction","detail":"{read_only:false; response_revision:11760; number_of_response:1; }","duration":"123.341063ms","start":"2026-08-10T22:48:25.03156Z","end":"2026-08-10T22:48:25.154901Z","steps":["trace[342660542] 'process raft request' (duration: 123.030305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:48:25.155141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.616258ms","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-10T22:48:25.155204Z","caller":"traceutil/trace.go:171","msg":"trace[220185817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11760; }","duration":"121.73031ms","start":"2026-08-10T22:48:25.033457Z","end":"2026-08-10T22:48:25.155187Z","steps":["trace[220185817] 'agreement among raft nodes before linearized reading' (duration: 121.577276ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:48:25.209305Z","caller":"traceutil/trace.go:171","msg":"trace[1416342422] transaction","detail":"{read_only:false; response_revision:11761; number_of_response:1; }","duration":"175.777503ms","start":"2026-08-10T22:48:25.033488Z","end":"2026-08-10T22:48:25.209266Z","steps":["trace[1416342422] 'process raft request' (duration: 140.381748ms)","trace[1416342422] 'compare' (duration: 35.178369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:48:25.209566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.002317ms","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-10T22:48:25.209642Z","caller":"traceutil/trace.go:171","msg":"trace[355784319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11762; }","duration":"104.12046ms","start":"2026-08-10T22:48:25.105504Z","end":"2026-08-10T22:48:25.209625Z","steps":["trace[355784319] 'agreement among raft nodes before linearized reading' (duration: 103.763901ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:51:00.610271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10542} {"level":"info","ts":"2026-08-10T22:51:00.689029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10542,"took":"76.295535ms","hash":3221000267,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":26578944,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-10T22:51:00.689112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221000267,"revision":10542,"compact-revision":8324} {"level":"info","ts":"2026-08-10T22:55:07.230763Z","caller":"traceutil/trace.go:171","msg":"trace[1003349071] transaction","detail":"{read_only:false; response_revision:14437; number_of_response:1; }","duration":"101.856711ms","start":"2026-08-10T22:55:07.12885Z","end":"2026-08-10T22:55:07.230706Z","steps":["trace[1003349071] 'process raft request' (duration: 101.592084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:07.594983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.301253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:55:07.595185Z","caller":"traceutil/trace.go:171","msg":"trace[1916208201] transaction","detail":"{read_only:false; response_revision:14439; number_of_response:1; }","duration":"360.151247ms","start":"2026-08-10T22:55:07.235003Z","end":"2026-08-10T22:55:07.595155Z","steps":["trace[1916208201] 'process raft request' (duration: 360.079785ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:07.595206Z","caller":"traceutil/trace.go:171","msg":"trace[1030320638] transaction","detail":"{read_only:false; response_revision:14438; number_of_response:1; }","duration":"360.210859ms","start":"2026-08-10T22:55:07.234963Z","end":"2026-08-10T22:55:07.595174Z","steps":["trace[1030320638] 'process raft request' (duration: 51.591117ms)","trace[1030320638] 'compare' (duration: 308.136579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:07.595257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:07.234977Z","time spent":"360.24742ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:07.595386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:07.234935Z","time spent":"360.360163ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:55:07.652021Z","caller":"traceutil/trace.go:171","msg":"trace[857236763] transaction","detail":"{read_only:false; response_revision:14440; number_of_response:1; }","duration":"416.407546ms","start":"2026-08-10T22:55:07.235583Z","end":"2026-08-10T22:55:07.65199Z","steps":["trace[857236763] 'process raft request' (duration: 416.213751ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:07.652252Z","caller":"traceutil/trace.go:171","msg":"trace[805216260] linearizableReadLoop","detail":"{readStateIndex:14931; appliedIndex:14929; }","duration":"356.091701ms","start":"2026-08-10T22:55:07.296113Z","end":"2026-08-10T22:55:07.652204Z","steps":["trace[805216260] 'read index received' (duration: 355.315971ms)","trace[805216260] 'applied index is now lower than readState.Index' (duration: 772.47µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:07.652252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:07.235573Z","time spent":"416.575571ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:07.652422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.275706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:55:07.652523Z","caller":"traceutil/trace.go:171","msg":"trace[1480258028] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:14441; }","duration":"356.4146ms","start":"2026-08-10T22:55:07.296077Z","end":"2026-08-10T22:55:07.652492Z","steps":["trace[1480258028] 'agreement among raft nodes before linearized reading' (duration: 356.260746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:07.652556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.40521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T22:55:07.652604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:07.296065Z","time spent":"356.524143ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-08-10T22:55:07.652351Z","caller":"traceutil/trace.go:171","msg":"trace[1674299076] transaction","detail":"{read_only:false; response_revision:14441; number_of_response:1; }","duration":"414.683852ms","start":"2026-08-10T22:55:07.237656Z","end":"2026-08-10T22:55:07.65234Z","steps":["trace[1674299076] 'process raft request' (duration: 414.286991ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:07.652656Z","caller":"traceutil/trace.go:171","msg":"trace[475265803] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:14441; }","duration":"201.586785ms","start":"2026-08-10T22:55:07.451044Z","end":"2026-08-10T22:55:07.652631Z","steps":["trace[475265803] 'agreement among raft nodes before linearized reading' (duration: 201.384709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:07.65274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:07.237644Z","time spent":"415.044592ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:08.854152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.350766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:55:08.854277Z","caller":"traceutil/trace.go:171","msg":"trace[1384439659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14449; }","duration":"127.550251ms","start":"2026-08-10T22:55:08.726706Z","end":"2026-08-10T22:55:08.854256Z","steps":["trace[1384439659] 'range keys from in-memory index tree' (duration: 127.246954ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:29.236019Z","caller":"traceutil/trace.go:171","msg":"trace[487927671] linearizableReadLoop","detail":"{readStateIndex:15244; appliedIndex:15243; }","duration":"279.028159ms","start":"2026-08-10T22:55:28.956961Z","end":"2026-08-10T22:55:29.235989Z","steps":["trace[487927671] 'read index received' (duration: 278.863475ms)","trace[487927671] 'applied index is now lower than readState.Index' (duration: 163.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:29.236168Z","caller":"traceutil/trace.go:171","msg":"trace[305329249] transaction","detail":"{read_only:false; response_revision:14741; number_of_response:1; }","duration":"351.834929ms","start":"2026-08-10T22:55:28.884322Z","end":"2026-08-10T22:55:29.236157Z","steps":["trace[305329249] 'process raft request' (duration: 351.4786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:29.236283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:28.88427Z","time spent":"351.928972ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:29.236421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.074026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf840e35-557b-41b5-8693-4b4d3fe34dd7\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-08-10T22:55:29.236456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.47813ms","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-10T22:55:29.236502Z","caller":"traceutil/trace.go:171","msg":"trace[1904556678] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf840e35-557b-41b5-8693-4b4d3fe34dd7; range_end:; response_count:1; response_revision:14741; }","duration":"249.172479ms","start":"2026-08-10T22:55:28.987311Z","end":"2026-08-10T22:55:29.236483Z","steps":["trace[1904556678] 'agreement among raft nodes before linearized reading' (duration: 248.926732ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:29.236556Z","caller":"traceutil/trace.go:171","msg":"trace[1110458011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14741; }","duration":"279.616164ms","start":"2026-08-10T22:55:28.956922Z","end":"2026-08-10T22:55:29.236538Z","steps":["trace[1110458011] 'agreement among raft nodes before linearized reading' (duration: 279.390578ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:29.345854Z","caller":"traceutil/trace.go:171","msg":"trace[646379410] transaction","detail":"{read_only:false; response_revision:14742; number_of_response:1; }","duration":"105.735651ms","start":"2026-08-10T22:55:29.240092Z","end":"2026-08-10T22:55:29.345828Z","steps":["trace[646379410] 'process raft request' (duration: 97.178428ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:56:00.617827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12798} {"level":"info","ts":"2026-08-10T22:56:00.692025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12798,"took":"72.361441ms","hash":1966694879,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":28909568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-10T22:56:00.692089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1966694879,"revision":12798,"compact-revision":10542} {"level":"info","ts":"2026-08-10T22:56:54.792207Z","caller":"traceutil/trace.go:171","msg":"trace[344523238] transaction","detail":"{read_only:false; response_revision:15556; number_of_response:1; }","duration":"238.144851ms","start":"2026-08-10T22:56:54.554019Z","end":"2026-08-10T22:56:54.792164Z","steps":["trace[344523238] 'process raft request' (duration: 226.309552ms)","trace[344523238] 'compare' (duration: 11.651444ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:56:54.827888Z","caller":"traceutil/trace.go:171","msg":"trace[193224872] linearizableReadLoop","detail":"{readStateIndex:16084; appliedIndex:16082; }","duration":"101.582605ms","start":"2026-08-10T22:56:54.726285Z","end":"2026-08-10T22:56:54.827867Z","steps":["trace[193224872] 'read index received' (duration: 54.057203ms)","trace[193224872] 'applied index is now lower than readState.Index' (duration: 47.524242ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:56:54.828013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.719888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:56:54.827979Z","caller":"traceutil/trace.go:171","msg":"trace[2121543350] transaction","detail":"{read_only:false; response_revision:15557; number_of_response:1; }","duration":"166.818828ms","start":"2026-08-10T22:56:54.661106Z","end":"2026-08-10T22:56:54.827925Z","steps":["trace[2121543350] 'process raft request' (duration: 166.655073ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:56:54.828035Z","caller":"traceutil/trace.go:171","msg":"trace[324454662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15557; }","duration":"101.80796ms","start":"2026-08-10T22:56:54.726221Z","end":"2026-08-10T22:56:54.828029Z","steps":["trace[324454662] 'agreement among raft nodes before linearized reading' (duration: 101.748748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:58.890368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.257142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-10T22:59:58.890451Z","caller":"traceutil/trace.go:171","msg":"trace[243721300] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17028; }","duration":"103.390825ms","start":"2026-08-10T22:59:58.787043Z","end":"2026-08-10T22:59:58.890434Z","steps":["trace[243721300] 'range keys from in-memory index tree' (duration: 103.104787ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:01:00.624582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14950} {"level":"info","ts":"2026-08-10T23:01:00.716178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14950,"took":"88.572678ms","hash":1806160248,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:01:00.716254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1806160248,"revision":14950,"compact-revision":12798} {"level":"info","ts":"2026-08-10T23:02:51.529241Z","caller":"traceutil/trace.go:171","msg":"trace[688533733] transaction","detail":"{read_only:false; response_revision:18223; number_of_response:1; }","duration":"150.430813ms","start":"2026-08-10T23:02:51.378774Z","end":"2026-08-10T23:02:51.529204Z","steps":["trace[688533733] 'process raft request' (duration: 150.197277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:58.193793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.612176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-10T23:02:58.193855Z","caller":"traceutil/trace.go:171","msg":"trace[901008501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18263; }","duration":"219.684398ms","start":"2026-08-10T23:02:57.974158Z","end":"2026-08-10T23:02:58.193843Z","steps":["trace[901008501] 'range keys from in-memory index tree' (duration: 219.447362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:58.19401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.981105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:02:58.194029Z","caller":"traceutil/trace.go:171","msg":"trace[967533582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18263; }","duration":"210.021927ms","start":"2026-08-10T23:02:57.984002Z","end":"2026-08-10T23:02:58.194024Z","steps":["trace[967533582] 'range keys from in-memory index tree' (duration: 209.918034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:58.194128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.38567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-10T23:02:58.194143Z","caller":"traceutil/trace.go:171","msg":"trace[972441876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18263; }","duration":"209.41234ms","start":"2026-08-10T23:02:57.984724Z","end":"2026-08-10T23:02:58.194137Z","steps":["trace[972441876] 'range keys from in-memory index tree' (duration: 209.337689ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:02:58.611392Z","caller":"traceutil/trace.go:171","msg":"trace[327060237] transaction","detail":"{read_only:false; response_revision:18268; number_of_response:1; }","duration":"331.990006ms","start":"2026-08-10T23:02:58.279385Z","end":"2026-08-10T23:02:58.611375Z","steps":["trace[327060237] 'process raft request' (duration: 331.886964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:58.611511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:02:58.279349Z","time spent":"332.097629ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20864,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:02:58.611655Z","caller":"traceutil/trace.go:171","msg":"trace[1585334993] linearizableReadLoop","detail":"{readStateIndex:18881; appliedIndex:18881; }","duration":"101.24429ms","start":"2026-08-10T23:02:58.510341Z","end":"2026-08-10T23:02:58.611585Z","steps":["trace[1585334993] 'read index received' (duration: 101.229389ms)","trace[1585334993] 'applied index is now lower than readState.Index' (duration: 12.05µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:02:58.611773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.416974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:02:58.611848Z","caller":"traceutil/trace.go:171","msg":"trace[1196569408] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:18268; }","duration":"101.522627ms","start":"2026-08-10T23:02:58.510306Z","end":"2026-08-10T23:02:58.611829Z","steps":["trace[1196569408] 'agreement among raft nodes before linearized reading' (duration: 101.410924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:59.338396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.274214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-10T23:02:59.338479Z","caller":"traceutil/trace.go:171","msg":"trace[1686163362] linearizableReadLoop","detail":"{readStateIndex:18883; appliedIndex:18882; }","duration":"206.998018ms","start":"2026-08-10T23:02:59.131466Z","end":"2026-08-10T23:02:59.338464Z","steps":["trace[1686163362] 'read index received' (duration: 91.673µs)","trace[1686163362] 'applied index is now lower than readState.Index' (duration: 206.905115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:02:59.338841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.373307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-10T23:02:59.338871Z","caller":"traceutil/trace.go:171","msg":"trace[506300030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18269; }","duration":"207.47055ms","start":"2026-08-10T23:02:59.131393Z","end":"2026-08-10T23:02:59.338863Z","steps":["trace[506300030] 'agreement among raft nodes before linearized reading' (duration: 207.326716ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:02:59.389839Z","caller":"traceutil/trace.go:171","msg":"trace[1476261884] transaction","detail":"{read_only:false; response_revision:18270; number_of_response:1; }","duration":"204.376679ms","start":"2026-08-10T23:02:59.185443Z","end":"2026-08-10T23:02:59.38982Z","steps":["trace[1476261884] 'process raft request' (duration: 204.03788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:02:59.390441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.063278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tp4xs\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-08-10T23:02:59.39049Z","caller":"traceutil/trace.go:171","msg":"trace[585180554] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tp4xs; range_end:; response_count:1; response_revision:18270; }","duration":"215.161921ms","start":"2026-08-10T23:02:59.175317Z","end":"2026-08-10T23:02:59.390479Z","steps":["trace[585180554] 'agreement among raft nodes before linearized reading' (duration: 215.010847ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.392015Z","caller":"traceutil/trace.go:171","msg":"trace[722655599] transaction","detail":"{read_only:false; response_revision:19357; number_of_response:1; }","duration":"136.121149ms","start":"2026-08-10T23:04:52.255865Z","end":"2026-08-10T23:04:52.391986Z","steps":["trace[722655599] 'process raft request' (duration: 135.951715ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.401699Z","caller":"traceutil/trace.go:171","msg":"trace[138060467] transaction","detail":"{read_only:false; response_revision:19358; number_of_response:1; }","duration":"144.377144ms","start":"2026-08-10T23:04:52.257292Z","end":"2026-08-10T23:04:52.401669Z","steps":["trace[138060467] 'process raft request' (duration: 144.1872ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.401877Z","caller":"traceutil/trace.go:171","msg":"trace[2094483364] transaction","detail":"{read_only:false; response_revision:19359; number_of_response:1; }","duration":"143.872281ms","start":"2026-08-10T23:04:52.257986Z","end":"2026-08-10T23:04:52.401858Z","steps":["trace[2094483364] 'process raft request' (duration: 143.640475ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.402063Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:04:52.402139Z","caller":"traceutil/trace.go:171","msg":"trace[1452859702] transaction","detail":"{read_only:false; response_revision:19360; number_of_response:1; }","duration":"132.744642ms","start":"2026-08-10T23:04:52.269382Z","end":"2026-08-10T23:04:52.402127Z","steps":["trace[1452859702] 'process raft request' (duration: 132.395772ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.409265Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-08-10T23:04:52.409426Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-08-10T23:06:00.630532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17480} {"level":"info","ts":"2026-08-10T23:06:00.735197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17480,"took":"100.938012ms","hash":3450599131,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":35078144,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-10T23:06:00.735268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450599131,"revision":17480,"compact-revision":14950} {"level":"info","ts":"2026-08-10T23:09:05.643775Z","caller":"traceutil/trace.go:171","msg":"trace[1663984387] transaction","detail":"{read_only:false; response_revision:21112; number_of_response:1; }","duration":"164.748035ms","start":"2026-08-10T23:09:05.478991Z","end":"2026-08-10T23:09:05.643739Z","steps":["trace[1663984387] 'process raft request' (duration: 164.58216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.015676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.057353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:06.015819Z","caller":"traceutil/trace.go:171","msg":"trace[84126644] linearizableReadLoop","detail":"{readStateIndex:21818; appliedIndex:21816; }","duration":"417.001633ms","start":"2026-08-10T23:09:05.598794Z","end":"2026-08-10T23:09:06.015795Z","steps":["trace[84126644] 'read index received' (duration: 44.758737ms)","trace[84126644] 'applied index is now lower than readState.Index' (duration: 372.241026ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:06.015925Z","caller":"traceutil/trace.go:171","msg":"trace[877306039] transaction","detail":"{read_only:false; response_revision:21113; number_of_response:1; }","duration":"488.367474ms","start":"2026-08-10T23:09:05.52754Z","end":"2026-08-10T23:09:06.015907Z","steps":["trace[877306039] 'process raft request' (duration: 337.976113ms)","trace[877306039] 'compare' (duration: 149.679883ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:06.016007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:05.527514Z","time spent":"488.447486ms","remote":"127.0.0.1:53782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:06.016678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.886618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-10T23:09:06.016735Z","caller":"traceutil/trace.go:171","msg":"trace[2054304176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21113; }","duration":"417.99044ms","start":"2026-08-10T23:09:05.598731Z","end":"2026-08-10T23:09:06.016721Z","steps":["trace[2054304176] 'agreement among raft nodes before linearized reading' (duration: 417.816835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.016762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:05.598704Z","time spent":"418.052072ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T23:09:06.03351Z","caller":"traceutil/trace.go:171","msg":"trace[22424830] transaction","detail":"{read_only:false; response_revision:21114; number_of_response:1; }","duration":"114.151367ms","start":"2026-08-10T23:09:05.919336Z","end":"2026-08-10T23:09:06.033487Z","steps":["trace[22424830] 'process raft request' (duration: 114.035563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.033793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.221943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-10T23:09:06.033806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.9626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:06.033948Z","caller":"traceutil/trace.go:171","msg":"trace[399068552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21114; }","duration":"308.144596ms","start":"2026-08-10T23:09:05.72577Z","end":"2026-08-10T23:09:06.033914Z","steps":["trace[399068552] 'agreement among raft nodes before linearized reading' (duration: 307.861608ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:06.03386Z","caller":"traceutil/trace.go:171","msg":"trace[720843868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21114; }","duration":"424.309095ms","start":"2026-08-10T23:09:05.609534Z","end":"2026-08-10T23:09:06.033843Z","steps":["trace[720843868] 'agreement among raft nodes before linearized reading' (duration: 424.146631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.034025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:05.725759Z","time spent":"308.251458ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T23:09:06.033743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.435509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:09:06.034095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:05.609524Z","time spent":"424.545131ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T23:09:06.034163Z","caller":"traceutil/trace.go:171","msg":"trace[627023209] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:21114; }","duration":"347.971204ms","start":"2026-08-10T23:09:05.686175Z","end":"2026-08-10T23:09:06.034147Z","steps":["trace[627023209] 'agreement among raft nodes before linearized reading' (duration: 347.45775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.034235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:05.686156Z","time spent":"348.030195ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:09:06.362845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.783522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:06.363008Z","caller":"traceutil/trace.go:171","msg":"trace[197626049] linearizableReadLoop","detail":"{readStateIndex:21820; appliedIndex:21819; }","duration":"329.395219ms","start":"2026-08-10T23:09:06.033588Z","end":"2026-08-10T23:09:06.362983Z","steps":["trace[197626049] 'read index received' (duration: 211.343501ms)","trace[197626049] 'applied index is now lower than readState.Index' (duration: 118.049698ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:06.363139Z","caller":"traceutil/trace.go:171","msg":"trace[455352546] transaction","detail":"{read_only:false; response_revision:21115; number_of_response:1; }","duration":"341.266339ms","start":"2026-08-10T23:09:06.021853Z","end":"2026-08-10T23:09:06.363119Z","steps":["trace[455352546] 'process raft request' (duration: 223.075188ms)","trace[455352546] 'compare' (duration: 117.594126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:06.363233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.021831Z","time spent":"341.354271ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:06.363444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.110703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18261"} {"level":"info","ts":"2026-08-10T23:09:06.363507Z","caller":"traceutil/trace.go:171","msg":"trace[1872397416] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21115; }","duration":"339.200635ms","start":"2026-08-10T23:09:06.024293Z","end":"2026-08-10T23:09:06.363493Z","steps":["trace[1872397416] 'agreement among raft nodes before linearized reading' (duration: 339.084822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.363531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.024279Z","time spent":"339.247486ms","remote":"127.0.0.1:43014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18285,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-08-10T23:09:06.717787Z","caller":"traceutil/trace.go:171","msg":"trace[1161915231] linearizableReadLoop","detail":"{readStateIndex:21821; appliedIndex:21820; }","duration":"354.700329ms","start":"2026-08-10T23:09:06.363058Z","end":"2026-08-10T23:09:06.717759Z","steps":["trace[1161915231] 'read index received' (duration: 354.528705ms)","trace[1161915231] 'applied index is now lower than readState.Index' (duration: 170.934µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:06.717893Z","caller":"traceutil/trace.go:171","msg":"trace[1484015239] transaction","detail":"{read_only:false; response_revision:21116; number_of_response:1; }","duration":"679.381456ms","start":"2026-08-10T23:09:06.038474Z","end":"2026-08-10T23:09:06.717856Z","steps":["trace[1484015239] 'process raft request' (duration: 679.128589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.717961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.992912ms","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-10T23:09:06.71799Z","caller":"traceutil/trace.go:171","msg":"trace[1203700318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21116; }","duration":"634.065393ms","start":"2026-08-10T23:09:06.083915Z","end":"2026-08-10T23:09:06.717981Z","steps":["trace[1203700318] 'agreement among raft nodes before linearized reading' (duration: 633.96121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.717965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.745648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-10T23:09:06.718016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.083906Z","time spent":"634.103955ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-10T23:09:06.718027Z","caller":"traceutil/trace.go:171","msg":"trace[1509054039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21116; }","duration":"594.835081ms","start":"2026-08-10T23:09:06.123177Z","end":"2026-08-10T23:09:06.718012Z","steps":["trace[1509054039] 'agreement among raft nodes before linearized reading' (duration: 594.612745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.718016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.904657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:09:06.718022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.165161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-10T23:09:06.718054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.123165Z","time spent":"594.880771ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T23:09:06.718077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.03845Z","time spent":"679.52152ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:06.718084Z","caller":"traceutil/trace.go:171","msg":"trace[1162613877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21116; }","duration":"683.01593ms","start":"2026-08-10T23:09:06.035049Z","end":"2026-08-10T23:09:06.718065Z","steps":["trace[1162613877] 'agreement among raft nodes before linearized reading' (duration: 682.879046ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:06.718095Z","caller":"traceutil/trace.go:171","msg":"trace[1991961973] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:21116; }","duration":"457.297394ms","start":"2026-08-10T23:09:06.260777Z","end":"2026-08-10T23:09:06.718074Z","steps":["trace[1991961973] 'agreement among raft nodes before linearized reading' (duration: 457.12275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.718138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.035037Z","time spent":"683.089453ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T23:09:06.71811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.29698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:06.718257Z","caller":"traceutil/trace.go:171","msg":"trace[1794234659] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:21116; }","duration":"657.424683ms","start":"2026-08-10T23:09:06.060754Z","end":"2026-08-10T23:09:06.718178Z","steps":["trace[1794234659] 'agreement among raft nodes before linearized reading' (duration: 657.273599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.718245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.365002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-10T23:09:06.718141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.260757Z","time spent":"457.369426ms","remote":"127.0.0.1:54280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:09:06.718336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.060741Z","time spent":"657.573267ms","remote":"127.0.0.1:54244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-10T23:09:06.718368Z","caller":"traceutil/trace.go:171","msg":"trace[1245348239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21116; }","duration":"634.567017ms","start":"2026-08-10T23:09:06.083772Z","end":"2026-08-10T23:09:06.718339Z","steps":["trace[1245348239] 'agreement among raft nodes before linearized reading' (duration: 634.200588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.718427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.083757Z","time spent":"634.6558ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T23:09:06.989012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.980712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:06.989262Z","caller":"traceutil/trace.go:171","msg":"trace[333043972] transaction","detail":"{read_only:false; response_revision:21119; number_of_response:1; }","duration":"265.427241ms","start":"2026-08-10T23:09:06.723815Z","end":"2026-08-10T23:09:06.989242Z","steps":["trace[333043972] 'process raft request' (duration: 265.3772ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:06.989318Z","caller":"traceutil/trace.go:171","msg":"trace[1218402504] transaction","detail":"{read_only:false; response_revision:21118; number_of_response:1; }","duration":"265.73478ms","start":"2026-08-10T23:09:06.723558Z","end":"2026-08-10T23:09:06.989293Z","steps":["trace[1218402504] 'process raft request' (duration: 265.576296ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:06.98932Z","caller":"traceutil/trace.go:171","msg":"trace[243969546] linearizableReadLoop","detail":"{readStateIndex:21822; appliedIndex:21821; }","duration":"265.869682ms","start":"2026-08-10T23:09:06.723413Z","end":"2026-08-10T23:09:06.989282Z","steps":["trace[243969546] 'read index received' (duration: 82.523481ms)","trace[243969546] 'applied index is now lower than readState.Index' (duration: 183.342531ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:06.98941Z","caller":"traceutil/trace.go:171","msg":"trace[630019376] transaction","detail":"{read_only:false; response_revision:21117; number_of_response:1; }","duration":"266.985692ms","start":"2026-08-10T23:09:06.722401Z","end":"2026-08-10T23:09:06.989387Z","steps":["trace[630019376] 'process raft request' (duration: 83.522578ms)","trace[630019376] 'compare' (duration: 182.860989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:06.989473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.055317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18261"} {"level":"info","ts":"2026-08-10T23:09:06.989502Z","caller":"traceutil/trace.go:171","msg":"trace[230547932] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21119; }","duration":"266.111409ms","start":"2026-08-10T23:09:06.72338Z","end":"2026-08-10T23:09:06.989492Z","steps":["trace[230547932] 'agreement among raft nodes before linearized reading' (duration: 266.046277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.394276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.9254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:07.394371Z","caller":"traceutil/trace.go:171","msg":"trace[1452316032] linearizableReadLoop","detail":"{readStateIndex:21826; appliedIndex:21824; }","duration":"404.963559ms","start":"2026-08-10T23:09:06.989394Z","end":"2026-08-10T23:09:07.394357Z","steps":["trace[1452316032] 'read index received' (duration: 29.666693ms)","trace[1452316032] 'applied index is now lower than readState.Index' (duration: 375.295906ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:07.394439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.690104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:07.394462Z","caller":"traceutil/trace.go:171","msg":"trace[919547424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21121; }","duration":"666.739606ms","start":"2026-08-10T23:09:06.727716Z","end":"2026-08-10T23:09:07.394455Z","steps":["trace[919547424] 'agreement among raft nodes before linearized reading' (duration: 666.696315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.394485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.727703Z","time spent":"666.777077ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T23:09:07.394487Z","caller":"traceutil/trace.go:171","msg":"trace[1670443548] transaction","detail":"{read_only:false; response_revision:21121; number_of_response:1; }","duration":"430.516546ms","start":"2026-08-10T23:09:06.963883Z","end":"2026-08-10T23:09:07.3944Z","steps":["trace[1670443548] 'process raft request' (duration: 224.366141ms)","trace[1670443548] 'compare' (duration: 205.802756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:07.395476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.963872Z","time spent":"431.172493ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:07.430394Z","caller":"traceutil/trace.go:171","msg":"trace[552661692] transaction","detail":"{read_only:false; response_revision:21122; number_of_response:1; }","duration":"227.535193ms","start":"2026-08-10T23:09:07.202825Z","end":"2026-08-10T23:09:07.43036Z","steps":["trace[552661692] 'process raft request' (duration: 226.820615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.437539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.291037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:07.43761Z","caller":"traceutil/trace.go:171","msg":"trace[1531141001] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:21123; }","duration":"352.4184ms","start":"2026-08-10T23:09:07.085173Z","end":"2026-08-10T23:09:07.437592Z","steps":["trace[1531141001] 'agreement among raft nodes before linearized reading' (duration: 352.296207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.437653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:07.085152Z","time spent":"352.484772ms","remote":"127.0.0.1:49964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:09:07.437598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.489101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:09:07.437896Z","caller":"traceutil/trace.go:171","msg":"trace[1918176156] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:21123; }","duration":"128.83729ms","start":"2026-08-10T23:09:07.309037Z","end":"2026-08-10T23:09:07.437874Z","steps":["trace[1918176156] 'agreement among raft nodes before linearized reading' (duration: 128.483321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.437779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.951314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:07.438071Z","caller":"traceutil/trace.go:171","msg":"trace[365566738] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21123; }","duration":"376.284361ms","start":"2026-08-10T23:09:07.061763Z","end":"2026-08-10T23:09:07.438048Z","steps":["trace[365566738] 'agreement among raft nodes before linearized reading' (duration: 375.895541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.438166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:07.061749Z","time spent":"376.403125ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-10T23:09:07.438994Z","caller":"traceutil/trace.go:171","msg":"trace[1100366791] transaction","detail":"{read_only:false; response_revision:21123; number_of_response:1; }","duration":"138.834849ms","start":"2026-08-10T23:09:07.300033Z","end":"2026-08-10T23:09:07.438868Z","steps":["trace[1100366791] 'process raft request' (duration: 137.244339ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:20.552662Z","caller":"traceutil/trace.go:171","msg":"trace[1083144600] transaction","detail":"{read_only:false; response_revision:21186; number_of_response:1; }","duration":"123.709645ms","start":"2026-08-10T23:09:20.428924Z","end":"2026-08-10T23:09:20.552633Z","steps":["trace[1083144600] 'process raft request' (duration: 123.525461ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:00.644504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19864} {"level":"info","ts":"2026-08-10T23:11:00.75495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19864,"took":"107.371949ms","hash":2703888430,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:11:00.755034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2703888430,"revision":19864,"compact-revision":17480} {"level":"info","ts":"2026-08-10T23:13:09.382649Z","caller":"traceutil/trace.go:171","msg":"trace[1089058565] transaction","detail":"{read_only:false; response_revision:23639; number_of_response:1; }","duration":"338.73106ms","start":"2026-08-10T23:13:09.043898Z","end":"2026-08-10T23:13:09.382629Z","steps":["trace[1089058565] 'process raft request' (duration: 338.591636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:09.382798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:09.043879Z","time spent":"338.840902ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:16:00.650085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22095} {"level":"info","ts":"2026-08-10T23:16:00.757832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22095,"took":"104.517308ms","hash":395313519,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":43782144,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-10T23:16:00.757984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395313519,"revision":22095,"compact-revision":19864} {"level":"warn","ts":"2026-08-10T23:18:21.84163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.371521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:18:21.841789Z","caller":"traceutil/trace.go:171","msg":"trace[44565190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26279; }","duration":"115.569897ms","start":"2026-08-10T23:18:21.726179Z","end":"2026-08-10T23:18:21.841749Z","steps":["trace[44565190] 'range keys from in-memory index tree' (duration: 115.304039ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:00.112605Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ce4.wal"} {"level":"info","ts":"2026-08-10T23:21:00.656093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25245} {"level":"info","ts":"2026-08-10T23:21:00.793942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25245,"took":"134.977081ms","hash":1856096042,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30134272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:21:00.793997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1856096042,"revision":25245,"compact-revision":22095}