{"level":"warn","ts":"2026-07-08T09:02:34.471397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:02:34.471522Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T09:02:34.471615Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:02:34.471627Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-07-08T09:02:34.47165Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T09:02:34.47253Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-07-08T09:02:34.472624Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T09:02:34.477908Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.987211ms"} {"level":"info","ts":"2026-07-08T09:02:34.485346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-07-08T09:02:34.485491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T09:02:34.485532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-07-08T09:02:34.485581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T09:02:34.485611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-07-08T09:02:34.485694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-07-08T09:02:34.493061Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T09:02:34.494912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T09:02:34.496246Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T09:02:34.498517Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T09:02:34.498711Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T09:02:34.498812Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T09:02:34.498825Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T09:02:34.499045Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T09:02:34.50254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-07-08T09:02:34.50359Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-07-08T09:02:34.505201Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T09:02:34.505414Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T09:02:34.505449Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T09:02:34.505519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-07-08T09:02:34.505535Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-07-08T09:02:34.886336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T09:02:34.886414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T09:02:34.886444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-07-08T09:02:34.88646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-07-08T09:02:34.886468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-07-08T09:02:34.886484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-07-08T09:02:34.886494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-07-08T09:02:34.8877Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T09:02:34.887747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:02:34.887928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:02:34.88796Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T09:02:34.887988Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T09:02:34.888089Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:34.88917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:34.889329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:34.889361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:34.890626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-07-08T09:02:34.891094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-08T09:03:12.617488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.022459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:03:12.617624Z","caller":"traceutil/trace.go:171","msg":"trace[1261115516] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"149.526858ms","start":"2026-07-08T09:03:12.468085Z","end":"2026-07-08T09:03:12.617612Z","steps":["trace[1261115516] 'read index received' (duration: 50.314µs)","trace[1261115516] 'applied index is now lower than readState.Index' (duration: 149.475544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:03:12.617709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.630203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T09:03:12.617731Z","caller":"traceutil/trace.go:171","msg":"trace[791193096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"149.682967ms","start":"2026-07-08T09:03:12.468041Z","end":"2026-07-08T09:03:12.617724Z","steps":["trace[791193096] 'agreement among raft nodes before linearized reading' (duration: 149.626523ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:12.617705Z","caller":"traceutil/trace.go:171","msg":"trace[123426986] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"248.251733ms","start":"2026-07-08T09:03:12.369383Z","end":"2026-07-08T09:03:12.617634Z","steps":["trace[123426986] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; req_size:65417; } (duration: 237.907553ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:12.625343Z","caller":"traceutil/trace.go:171","msg":"trace[716174546] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"132.740159ms","start":"2026-07-08T09:03:12.492584Z","end":"2026-07-08T09:03:12.625324Z","steps":["trace[716174546] 'process raft request' (duration: 131.548909ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:19.800927Z","caller":"traceutil/trace.go:171","msg":"trace[1945856035] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"214.877822ms","start":"2026-07-08T09:03:19.586028Z","end":"2026-07-08T09:03:19.800906Z","steps":["trace[1945856035] 'process raft request' (duration: 214.765435ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:21.036248Z","caller":"traceutil/trace.go:171","msg":"trace[1929761739] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"111.839789ms","start":"2026-07-08T09:03:20.924371Z","end":"2026-07-08T09:03:21.03621Z","steps":["trace[1929761739] 'process raft request' (duration: 111.332835ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:35.676305Z","caller":"traceutil/trace.go:171","msg":"trace[1722599901] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"299.769008ms","start":"2026-07-08T09:03:35.376512Z","end":"2026-07-08T09:03:35.676281Z","steps":["trace[1722599901] 'process raft request' (duration: 299.613569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.566379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.403129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:04:54.566445Z","caller":"traceutil/trace.go:171","msg":"trace[354078786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"656.501063ms","start":"2026-07-08T09:04:53.909929Z","end":"2026-07-08T09:04:54.56643Z","steps":["trace[354078786] 'range keys from in-memory index tree' (duration: 656.348108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.566475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:53.909915Z","time spent":"656.553217ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T09:04:54.566423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.163998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-08T09:04:54.566637Z","caller":"traceutil/trace.go:171","msg":"trace[2008109306] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f; range_end:; response_count:1; response_revision:1849; }","duration":"556.410437ms","start":"2026-07-08T09:04:54.010205Z","end":"2026-07-08T09:04:54.566616Z","steps":["trace[2008109306] 'range keys from in-memory index tree' (duration: 555.934008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.566699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.010188Z","time spent":"556.49674ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f\" "} {"level":"warn","ts":"2026-07-08T09:04:54.572464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901809199055816,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T09:04:54.778319Z","caller":"traceutil/trace.go:171","msg":"trace[841926134] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"706.654457ms","start":"2026-07-08T09:04:54.071644Z","end":"2026-07-08T09:04:54.778298Z","steps":["trace[841926134] 'read index received' (duration: 706.448279ms)","trace[841926134] 'applied index is now lower than readState.Index' (duration: 205.318µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:04:54.778382Z","caller":"traceutil/trace.go:171","msg":"trace[1311043206] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"753.122092ms","start":"2026-07-08T09:04:54.02523Z","end":"2026-07-08T09:04:54.778352Z","steps":["trace[1311043206] 'process raft request' (duration: 752.936675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.778504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.856764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-08T09:04:54.778535Z","caller":"traceutil/trace.go:171","msg":"trace[515498726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1850; }","duration":"706.959329ms","start":"2026-07-08T09:04:54.071567Z","end":"2026-07-08T09:04:54.778526Z","steps":["trace[515498726] 'agreement among raft nodes before linearized reading' (duration: 706.837655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.778562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.071538Z","time spent":"707.017301ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T09:04:54.778604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.025205Z","time spent":"753.286689ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:04:54.778635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.813208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T09:04:54.778714Z","caller":"traceutil/trace.go:171","msg":"trace[459945340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"508.938192ms","start":"2026-07-08T09:04:54.269756Z","end":"2026-07-08T09:04:54.778694Z","steps":["trace[459945340] 'agreement among raft nodes before linearized reading' (duration: 508.692362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.778756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.269734Z","time spent":"509.014654ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T09:04:54.778942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.246776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-08T09:04:54.778975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.873171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:04:54.779012Z","caller":"traceutil/trace.go:171","msg":"trace[1062785658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f; range_end:; response_count:1; response_revision:1850; }","duration":"489.337659ms","start":"2026-07-08T09:04:54.289655Z","end":"2026-07-08T09:04:54.778993Z","steps":["trace[1062785658] 'agreement among raft nodes before linearized reading' (duration: 489.128ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:04:54.779041Z","caller":"traceutil/trace.go:171","msg":"trace[1620818912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"210.965133ms","start":"2026-07-08T09:04:54.568058Z","end":"2026-07-08T09:04:54.779023Z","steps":["trace[1620818912] 'agreement among raft nodes before linearized reading' (duration: 210.606559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:54.77906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.289635Z","time spent":"489.412733ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbfed2c5-ae6f-425a-9f79-3b37147f528f\" "} {"level":"info","ts":"2026-07-08T09:04:55.157133Z","caller":"traceutil/trace.go:171","msg":"trace[1049318944] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"373.43344ms","start":"2026-07-08T09:04:54.783672Z","end":"2026-07-08T09:04:55.157106Z","steps":["trace[1049318944] 'process raft request' (duration: 367.578175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:55.157295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.783655Z","time spent":"373.561697ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:04:55.15743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.899461ms","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":"info","ts":"2026-07-08T09:04:55.157493Z","caller":"traceutil/trace.go:171","msg":"trace[1507439365] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1853; }","duration":"219.067828ms","start":"2026-07-08T09:04:54.938408Z","end":"2026-07-08T09:04:55.157475Z","steps":["trace[1507439365] 'agreement among raft nodes before linearized reading' (duration: 218.935191ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:04:55.157295Z","caller":"traceutil/trace.go:171","msg":"trace[727032640] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1924; }","duration":"218.750823ms","start":"2026-07-08T09:04:54.938526Z","end":"2026-07-08T09:04:55.157277Z","steps":["trace[727032640] 'read index received' (duration: 212.681038ms)","trace[727032640] 'applied index is now lower than readState.Index' (duration: 6.068335ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:04:55.15776Z","caller":"traceutil/trace.go:171","msg":"trace[1128621552] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"372.632408ms","start":"2026-07-08T09:04:54.785096Z","end":"2026-07-08T09:04:55.157729Z","steps":["trace[1128621552] 'process raft request' (duration: 371.948081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:04:55.157856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:04:54.785087Z","time spent":"372.718073ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:04:55.610474Z","caller":"traceutil/trace.go:171","msg":"trace[528385295] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"267.417065ms","start":"2026-07-08T09:04:55.343026Z","end":"2026-07-08T09:04:55.610443Z","steps":["trace[528385295] 'process raft request' (duration: 200.526412ms)","trace[528385295] 'compare' (duration: 66.703465ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:10.233089Z","caller":"traceutil/trace.go:171","msg":"trace[169377987] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"219.954953ms","start":"2026-07-08T09:05:10.013111Z","end":"2026-07-08T09:05:10.233066Z","steps":["trace[169377987] 'process raft request' (duration: 219.833298ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:10.241136Z","caller":"traceutil/trace.go:171","msg":"trace[1000800494] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"227.937538ms","start":"2026-07-08T09:05:10.013184Z","end":"2026-07-08T09:05:10.241121Z","steps":["trace[1000800494] 'process raft request' (duration: 227.805411ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:10.241502Z","caller":"traceutil/trace.go:171","msg":"trace[2108367701] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"227.3119ms","start":"2026-07-08T09:05:10.014175Z","end":"2026-07-08T09:05:10.241487Z","steps":["trace[2108367701] 'process raft request' (duration: 226.905552ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:10.241637Z","caller":"traceutil/trace.go:171","msg":"trace[1833956276] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"227.169184ms","start":"2026-07-08T09:05:10.014448Z","end":"2026-07-08T09:05:10.241617Z","steps":["trace[1833956276] 'process raft request' (duration: 226.87519ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:10.241628Z","caller":"traceutil/trace.go:171","msg":"trace[797134278] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"152.302831ms","start":"2026-07-08T09:05:10.089297Z","end":"2026-07-08T09:05:10.2416Z","steps":["trace[797134278] 'process raft request' (duration: 152.202716ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:18.277502Z","caller":"traceutil/trace.go:171","msg":"trace[1079966967] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2103; }","duration":"350.994865ms","start":"2026-07-08T09:05:17.926483Z","end":"2026-07-08T09:05:18.277477Z","steps":["trace[1079966967] 'read index received' (duration: 346.850442ms)","trace[1079966967] 'applied index is now lower than readState.Index' (duration: 4.143193ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:18.277521Z","caller":"traceutil/trace.go:171","msg":"trace[266668312] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"447.449971ms","start":"2026-07-08T09:05:17.830044Z","end":"2026-07-08T09:05:18.277494Z","steps":["trace[266668312] 'process raft request' (duration: 443.34371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:18.277676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:17.830025Z","time spent":"447.577777ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:05:18.277814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.762024ms","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-07-08T09:05:18.27791Z","caller":"traceutil/trace.go:171","msg":"trace[1551831773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2026; }","duration":"196.90158ms","start":"2026-07-08T09:05:18.080985Z","end":"2026-07-08T09:05:18.277886Z","steps":["trace[1551831773] 'agreement among raft nodes before linearized reading' (duration: 196.68402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:18.278251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.75888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T09:05:18.278299Z","caller":"traceutil/trace.go:171","msg":"trace[300740540] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:2026; }","duration":"351.854595ms","start":"2026-07-08T09:05:17.926432Z","end":"2026-07-08T09:05:18.278286Z","steps":["trace[300740540] 'agreement among raft nodes before linearized reading' (duration: 351.182484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:18.278514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:17.926413Z","time spent":"352.002142ms","remote":"127.0.0.1:52040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"info","ts":"2026-07-08T09:05:28.497507Z","caller":"traceutil/trace.go:171","msg":"trace[755940507] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"145.535755ms","start":"2026-07-08T09:05:28.351949Z","end":"2026-07-08T09:05:28.497485Z","steps":["trace[755940507] 'read index received' (duration: 145.399149ms)","trace[755940507] 'applied index is now lower than readState.Index' (duration: 135.566µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:28.497547Z","caller":"traceutil/trace.go:171","msg":"trace[70820815] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"189.001242ms","start":"2026-07-08T09:05:28.308516Z","end":"2026-07-08T09:05:28.497517Z","steps":["trace[70820815] 'process raft request' (duration: 188.820243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:28.497875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.915053ms","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-07-08T09:05:28.497943Z","caller":"traceutil/trace.go:171","msg":"trace[934826952] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2089; }","duration":"146.073581ms","start":"2026-07-08T09:05:28.351856Z","end":"2026-07-08T09:05:28.497929Z","steps":["trace[934826952] 'agreement among raft nodes before linearized reading' (duration: 145.742375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:33.960907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.94797ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-08T09:05:33.961851Z","caller":"traceutil/trace.go:171","msg":"trace[1623582972] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"192.294898ms","start":"2026-07-08T09:05:33.769535Z","end":"2026-07-08T09:05:33.96183Z","steps":["trace[1623582972] 'process raft request' (duration: 191.483657ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:34.005632Z","caller":"traceutil/trace.go:171","msg":"trace[1951399369] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"177.476096ms","start":"2026-07-08T09:05:33.828128Z","end":"2026-07-08T09:05:34.005604Z","steps":["trace[1951399369] 'process raft request' (duration: 154.27196ms)","trace[1951399369] 'compare' (duration: 22.787295ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:34.005776Z","caller":"traceutil/trace.go:171","msg":"trace[1459609633] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2196; }","duration":"135.589236ms","start":"2026-07-08T09:05:33.870162Z","end":"2026-07-08T09:05:34.005751Z","steps":["trace[1459609633] 'read index received' (duration: 68.951835ms)","trace[1459609633] 'applied index is now lower than readState.Index' (duration: 66.636401ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:34.006053Z","caller":"traceutil/trace.go:171","msg":"trace[467345137] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"155.136682ms","start":"2026-07-08T09:05:33.850901Z","end":"2026-07-08T09:05:34.006037Z","steps":["trace[467345137] 'process raft request' (duration: 154.622487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:34.006231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.05257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T09:05:34.006286Z","caller":"traceutil/trace.go:171","msg":"trace[153741059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2118; }","duration":"136.140614ms","start":"2026-07-08T09:05:33.87013Z","end":"2026-07-08T09:05:34.006271Z","steps":["trace[153741059] 'agreement among raft nodes before linearized reading' (duration: 135.958576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:35.872528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.559254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T09:05:35.872615Z","caller":"traceutil/trace.go:171","msg":"trace[328876427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"428.695061ms","start":"2026-07-08T09:05:35.443902Z","end":"2026-07-08T09:05:35.872597Z","steps":["trace[328876427] 'range keys from in-memory index tree' (duration: 428.385227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:35.872652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:35.443883Z","time spent":"428.760804ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T09:05:35.872524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.405858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T09:05:35.872835Z","caller":"traceutil/trace.go:171","msg":"trace[575832406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2121; }","duration":"498.758595ms","start":"2026-07-08T09:05:35.374067Z","end":"2026-07-08T09:05:35.872825Z","steps":["trace[575832406] 'range keys from in-memory index tree' (duration: 498.203348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:35.872865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:35.374052Z","time spent":"498.805108ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T09:05:35.872952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.318701ms","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-07-08T09:05:35.873044Z","caller":"traceutil/trace.go:171","msg":"trace[1500832775] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"286.459117ms","start":"2026-07-08T09:05:35.586558Z","end":"2026-07-08T09:05:35.873017Z","steps":["trace[1500832775] 'range keys from in-memory index tree' (duration: 286.146391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:35.874691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.167537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T09:05:35.874753Z","caller":"traceutil/trace.go:171","msg":"trace[2050695068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2121; }","duration":"496.251252ms","start":"2026-07-08T09:05:35.378488Z","end":"2026-07-08T09:05:35.874739Z","steps":["trace[2050695068] 'range keys from in-memory index tree' (duration: 496.048951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:35.87483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:35.378479Z","time spent":"496.338035ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T09:05:36.057692Z","caller":"traceutil/trace.go:171","msg":"trace[876957994] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"176.527928ms","start":"2026-07-08T09:05:35.88114Z","end":"2026-07-08T09:05:36.057667Z","steps":["trace[876957994] 'process raft request' (duration: 176.399922ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:36.188558Z","caller":"traceutil/trace.go:171","msg":"trace[242971438] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"221.779701ms","start":"2026-07-08T09:05:35.966758Z","end":"2026-07-08T09:05:36.188537Z","steps":["trace[242971438] 'process raft request' (duration: 221.709508ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:36.188653Z","caller":"traceutil/trace.go:171","msg":"trace[323788890] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"304.343248ms","start":"2026-07-08T09:05:35.884296Z","end":"2026-07-08T09:05:36.188639Z","steps":["trace[323788890] 'process raft request' (duration: 304.000911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:36.189235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:35.884283Z","time spent":"304.891295ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:05:36.188964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.804197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:05:36.188766Z","caller":"traceutil/trace.go:171","msg":"trace[3267815] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"303.811411ms","start":"2026-07-08T09:05:35.884927Z","end":"2026-07-08T09:05:36.188738Z","steps":["trace[3267815] 'process raft request' (duration: 303.503386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:36.189695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:05:35.88491Z","time spent":"304.752899ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:05:36.188851Z","caller":"traceutil/trace.go:171","msg":"trace[2144722626] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"178.009654ms","start":"2026-07-08T09:05:36.010826Z","end":"2026-07-08T09:05:36.188836Z","steps":["trace[2144722626] 'process raft request' (duration: 177.680648ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:36.188856Z","caller":"traceutil/trace.go:171","msg":"trace[1001351870] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2203; }","duration":"278.694052ms","start":"2026-07-08T09:05:35.910138Z","end":"2026-07-08T09:05:36.188832Z","steps":["trace[1001351870] 'read index received' (duration: 147.372779ms)","trace[1001351870] 'applied index is now lower than readState.Index' (duration: 131.320103ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:36.1889Z","caller":"traceutil/trace.go:171","msg":"trace[1552005733] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"176.410733ms","start":"2026-07-08T09:05:36.012481Z","end":"2026-07-08T09:05:36.188892Z","steps":["trace[1552005733] 'process raft request' (duration: 176.194783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:36.19021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.077822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T09:05:36.190274Z","caller":"traceutil/trace.go:171","msg":"trace[1607690306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2127; }","duration":"129.168116ms","start":"2026-07-08T09:05:36.061095Z","end":"2026-07-08T09:05:36.190263Z","steps":["trace[1607690306] 'agreement among raft nodes before linearized reading' (duration: 129.04012ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:36.18937Z","caller":"traceutil/trace.go:171","msg":"trace[1205389393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2127; }","duration":"279.237489ms","start":"2026-07-08T09:05:35.910094Z","end":"2026-07-08T09:05:36.189331Z","steps":["trace[1205389393] 'agreement among raft nodes before linearized reading' (duration: 278.815238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:36.602843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.749986ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:05:36.60301Z","caller":"traceutil/trace.go:171","msg":"trace[452720553] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2128; }","duration":"132.928025ms","start":"2026-07-08T09:05:36.470061Z","end":"2026-07-08T09:05:36.602989Z","steps":["trace[452720553] 'range keys from in-memory index tree' (duration: 132.736793ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:06:57.924456Z","caller":"traceutil/trace.go:171","msg":"trace[1022331907] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"359.034129ms","start":"2026-07-08T09:06:57.565403Z","end":"2026-07-08T09:06:57.924437Z","steps":["trace[1022331907] 'process raft request' (duration: 358.905882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:57.924585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:57.565387Z","time spent":"359.132206ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:06:58.320322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.274823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-07-08T09:06:58.320432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.631445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c045410dafcf87\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-08T09:06:58.320584Z","caller":"traceutil/trace.go:171","msg":"trace[124602442] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2676; }","duration":"123.457943ms","start":"2026-07-08T09:06:58.196993Z","end":"2026-07-08T09:06:58.320451Z","steps":["trace[124602442] 'range keys from in-memory index tree' (duration: 123.145315ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:06:58.320599Z","caller":"traceutil/trace.go:171","msg":"trace[1898034443] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c045410dafcf87; range_end:; response_count:1; response_revision:2676; }","duration":"173.762762ms","start":"2026-07-08T09:06:58.146734Z","end":"2026-07-08T09:06:58.320497Z","steps":["trace[1898034443] 'range keys from in-memory index tree' (duration: 173.251573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.095445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.746625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T09:06:59.095529Z","caller":"traceutil/trace.go:171","msg":"trace[1065564294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2678; }","duration":"639.876422ms","start":"2026-07-08T09:06:58.455635Z","end":"2026-07-08T09:06:59.095512Z","steps":["trace[1065564294] 'range keys from in-memory index tree' (duration: 639.471519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.095914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.455619Z","time spent":"639.940626ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T09:06:59.096045Z","caller":"traceutil/trace.go:171","msg":"trace[390948030] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"638.814412ms","start":"2026-07-08T09:06:58.457211Z","end":"2026-07-08T09:06:59.096026Z","steps":["trace[390948030] 'process raft request' (duration: 594.92249ms)","trace[390948030] 'compare' (duration: 43.758334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:06:59.096159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.457189Z","time spent":"638.908036ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:06:59.114993Z","caller":"traceutil/trace.go:171","msg":"trace[260884783] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"636.064253ms","start":"2026-07-08T09:06:58.478914Z","end":"2026-07-08T09:06:59.114978Z","steps":["trace[260884783] 'process raft request' (duration: 635.975438ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:06:59.115096Z","caller":"traceutil/trace.go:171","msg":"trace[1204133509] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"204.281055ms","start":"2026-07-08T09:06:58.910752Z","end":"2026-07-08T09:06:59.115033Z","steps":["trace[1204133509] 'process raft request' (duration: 204.175859ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:06:59.114985Z","caller":"traceutil/trace.go:171","msg":"trace[1099559893] linearizableReadLoop","detail":"{readStateIndex:2783; appliedIndex:2780; }","duration":"531.820457ms","start":"2026-07-08T09:06:58.583145Z","end":"2026-07-08T09:06:59.114966Z","steps":["trace[1099559893] 'read index received' (duration: 469.000734ms)","trace[1099559893] 'applied index is now lower than readState.Index' (duration: 62.818793ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:06:59.114985Z","caller":"traceutil/trace.go:171","msg":"trace[1349510131] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"636.242844ms","start":"2026-07-08T09:06:58.478724Z","end":"2026-07-08T09:06:59.114967Z","steps":["trace[1349510131] 'process raft request' (duration: 636.037572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.115137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.47889Z","time spent":"636.149747ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:06:59.11544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.281064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T09:06:59.115529Z","caller":"traceutil/trace.go:171","msg":"trace[826424876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2682; }","duration":"532.403471ms","start":"2026-07-08T09:06:58.583112Z","end":"2026-07-08T09:06:59.115515Z","steps":["trace[826424876] 'agreement among raft nodes before linearized reading' (duration: 531.913563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.115595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.583097Z","time spent":"532.486176ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T09:06:59.115542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.478688Z","time spent":"636.633616ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:06:59.131676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.272689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-08T09:06:59.131739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.504986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-08T09:06:59.13177Z","caller":"traceutil/trace.go:171","msg":"trace[1731240863] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2683; }","duration":"285.536449ms","start":"2026-07-08T09:06:58.846225Z","end":"2026-07-08T09:06:59.131762Z","steps":["trace[1731240863] 'agreement among raft nodes before linearized reading' (duration: 285.462674ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:06:59.131782Z","caller":"traceutil/trace.go:171","msg":"trace[619272628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2683; }","duration":"547.376175ms","start":"2026-07-08T09:06:58.584376Z","end":"2026-07-08T09:06:59.131752Z","steps":["trace[619272628] 'agreement among raft nodes before linearized reading' (duration: 547.228456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.131838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.58437Z","time spent":"547.459529ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T09:06:59.131878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.774485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:06:59.131899Z","caller":"traceutil/trace.go:171","msg":"trace[2018128906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2683; }","duration":"221.837928ms","start":"2026-07-08T09:06:58.910056Z","end":"2026-07-08T09:06:59.131893Z","steps":["trace[2018128906] 'agreement among raft nodes before linearized reading' (duration: 221.807477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.13201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.239149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-08T09:06:59.132387Z","caller":"traceutil/trace.go:171","msg":"trace[777863470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2683; }","duration":"529.614191ms","start":"2026-07-08T09:06:58.602743Z","end":"2026-07-08T09:06:59.132357Z","steps":["trace[777863470] 'agreement among raft nodes before linearized reading' (duration: 529.224029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:06:59.132546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:06:58.602728Z","time spent":"529.803123ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T09:10:49.270848Z","caller":"traceutil/trace.go:171","msg":"trace[5370192] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"116.674194ms","start":"2026-07-08T09:10:49.154154Z","end":"2026-07-08T09:10:49.270828Z","steps":["trace[5370192] 'process raft request' (duration: 116.520499ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:50.150193Z","caller":"traceutil/trace.go:171","msg":"trace[256670568] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"146.028538ms","start":"2026-07-08T09:10:50.004137Z","end":"2026-07-08T09:10:50.150166Z","steps":["trace[256670568] 'process raft request' (duration: 143.112876ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:50.161185Z","caller":"traceutil/trace.go:171","msg":"trace[15632384] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"150.08277ms","start":"2026-07-08T09:10:50.011071Z","end":"2026-07-08T09:10:50.161153Z","steps":["trace[15632384] 'process raft request' (duration: 149.873982ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:50.161492Z","caller":"traceutil/trace.go:171","msg":"trace[1421581164] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"149.303312ms","start":"2026-07-08T09:10:50.012167Z","end":"2026-07-08T09:10:50.161471Z","steps":["trace[1421581164] 'process raft request' (duration: 148.940039ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:50.161647Z","caller":"traceutil/trace.go:171","msg":"trace[1482636454] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"149.359413ms","start":"2026-07-08T09:10:50.01227Z","end":"2026-07-08T09:10:50.161629Z","steps":["trace[1482636454] 'process raft request' (duration: 149.046063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:55.68644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.848473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-08T09:10:55.686511Z","caller":"traceutil/trace.go:171","msg":"trace[2121512489] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4716; }","duration":"159.971797ms","start":"2026-07-08T09:10:55.52652Z","end":"2026-07-08T09:10:55.686492Z","steps":["trace[2121512489] 'range keys from in-memory index tree' (duration: 159.703998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.384153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.399915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:10:56.384615Z","caller":"traceutil/trace.go:171","msg":"trace[1326999764] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4897; }","duration":"296.631699ms","start":"2026-07-08T09:10:56.087966Z","end":"2026-07-08T09:10:56.384597Z","steps":["trace[1326999764] 'read index received' (duration: 157.941195ms)","trace[1326999764] 'applied index is now lower than readState.Index' (duration: 138.689384ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:10:56.384453Z","caller":"traceutil/trace.go:171","msg":"trace[2044677626] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"298.133554ms","start":"2026-07-08T09:10:56.08629Z","end":"2026-07-08T09:10:56.384424Z","steps":["trace[2044677626] 'process raft request' (duration: 159.309554ms)","trace[2044677626] 'compare' (duration: 138.290841ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:10:56.384826Z","caller":"traceutil/trace.go:171","msg":"trace[1837683039] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"298.489736ms","start":"2026-07-08T09:10:56.086319Z","end":"2026-07-08T09:10:56.384809Z","steps":["trace[1837683039] 'process raft request' (duration: 298.067641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.386592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.086305Z","time spent":"300.235208ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:10:56.384889Z","caller":"traceutil/trace.go:171","msg":"trace[1991778918] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"298.494707ms","start":"2026-07-08T09:10:56.086383Z","end":"2026-07-08T09:10:56.384877Z","steps":["trace[1991778918] 'process raft request' (duration: 298.095532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.386755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.086377Z","time spent":"300.357113ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:10:56.384902Z","caller":"traceutil/trace.go:171","msg":"trace[617722198] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"298.438414ms","start":"2026-07-08T09:10:56.086451Z","end":"2026-07-08T09:10:56.38489Z","steps":["trace[617722198] 'process raft request' (duration: 298.092702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.386969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.086206Z","time spent":"300.25282ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:10:56.387013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.086434Z","time spent":"300.463837ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:10:56.385052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.706963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-edb8aa21-ce2e-4a2c-be75-4885874ef1fe\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-08T09:10:56.387441Z","caller":"traceutil/trace.go:171","msg":"trace[1323715453] range","detail":"{range_begin:/registry/persistentvolumes/pvc-edb8aa21-ce2e-4a2c-be75-4885874ef1fe; range_end:; response_count:1; response_revision:4724; }","duration":"301.10352ms","start":"2026-07-08T09:10:56.086313Z","end":"2026-07-08T09:10:56.387417Z","steps":["trace[1323715453] 'agreement among raft nodes before linearized reading' (duration: 298.61447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.387506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.086304Z","time spent":"301.184022ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-edb8aa21-ce2e-4a2c-be75-4885874ef1fe\" "} {"level":"info","ts":"2026-07-08T09:10:56.420436Z","caller":"traceutil/trace.go:171","msg":"trace[1188203607] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"322.471855ms","start":"2026-07-08T09:10:56.097939Z","end":"2026-07-08T09:10:56.420411Z","steps":["trace[1188203607] 'process raft request' (duration: 322.247407ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.420463Z","caller":"traceutil/trace.go:171","msg":"trace[352146611] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"238.991506ms","start":"2026-07-08T09:10:56.181454Z","end":"2026-07-08T09:10:56.420445Z","steps":["trace[352146611] 'process raft request' (duration: 238.931534ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.42084Z","caller":"traceutil/trace.go:171","msg":"trace[771390988] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"239.705742ms","start":"2026-07-08T09:10:56.181111Z","end":"2026-07-08T09:10:56.420817Z","steps":["trace[771390988] 'process raft request' (duration: 239.239494ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.421029Z","caller":"traceutil/trace.go:171","msg":"trace[1058686520] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"239.475463ms","start":"2026-07-08T09:10:56.181534Z","end":"2026-07-08T09:10:56.421009Z","steps":["trace[1058686520] 'process raft request' (duration: 238.882312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.420567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:10:56.097923Z","time spent":"322.576039ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:10:56.420621Z","caller":"traceutil/trace.go:171","msg":"trace[1135084777] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"241.031179ms","start":"2026-07-08T09:10:56.17958Z","end":"2026-07-08T09:10:56.420612Z","steps":["trace[1135084777] 'process raft request' (duration: 240.724238ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.54721Z","caller":"traceutil/trace.go:171","msg":"trace[600502601] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"116.892424ms","start":"2026-07-08T09:10:56.430283Z","end":"2026-07-08T09:10:56.547176Z","steps":["trace[600502601] 'process raft request' (duration: 114.148655ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.547481Z","caller":"traceutil/trace.go:171","msg":"trace[810399678] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"116.788161ms","start":"2026-07-08T09:10:56.430666Z","end":"2026-07-08T09:10:56.547454Z","steps":["trace[810399678] 'process raft request' (duration: 116.449798ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.547786Z","caller":"traceutil/trace.go:171","msg":"trace[713102454] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"117.110273ms","start":"2026-07-08T09:10:56.430665Z","end":"2026-07-08T09:10:56.547775Z","steps":["trace[713102454] 'process raft request' (duration: 116.703348ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.547853Z","caller":"traceutil/trace.go:171","msg":"trace[477496706] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"117.124163ms","start":"2026-07-08T09:10:56.430721Z","end":"2026-07-08T09:10:56.547845Z","steps":["trace[477496706] 'process raft request' (duration: 116.973128ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.547978Z","caller":"traceutil/trace.go:171","msg":"trace[448633449] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"117.210126ms","start":"2026-07-08T09:10:56.430746Z","end":"2026-07-08T09:10:56.547956Z","steps":["trace[448633449] 'process raft request' (duration: 117.02885ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.547977Z","caller":"traceutil/trace.go:171","msg":"trace[37807520] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"116.960177ms","start":"2026-07-08T09:10:56.430979Z","end":"2026-07-08T09:10:56.547939Z","steps":["trace[37807520] 'process raft request' (duration: 116.852763ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:56.548082Z","caller":"traceutil/trace.go:171","msg":"trace[1795180049] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4907; }","duration":"105.723095ms","start":"2026-07-08T09:10:56.442346Z","end":"2026-07-08T09:10:56.548069Z","steps":["trace[1795180049] 'read index received' (duration: 102.014222ms)","trace[1795180049] 'applied index is now lower than readState.Index' (duration: 3.707733ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:10:56.548125Z","caller":"traceutil/trace.go:171","msg":"trace[1536938479] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"116.616395ms","start":"2026-07-08T09:10:56.431499Z","end":"2026-07-08T09:10:56.548116Z","steps":["trace[1536938479] 'process raft request' (duration: 116.361795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:10:56.548256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.885981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:10:56.548307Z","caller":"traceutil/trace.go:171","msg":"trace[1693178774] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4737; }","duration":"105.981054ms","start":"2026-07-08T09:10:56.442313Z","end":"2026-07-08T09:10:56.548294Z","steps":["trace[1693178774] 'agreement among raft nodes before linearized reading' (duration: 105.805658ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:59.190392Z","caller":"traceutil/trace.go:171","msg":"trace[640462892] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4944; }","duration":"117.513879ms","start":"2026-07-08T09:10:59.072863Z","end":"2026-07-08T09:10:59.190376Z","steps":["trace[640462892] 'read index received' (duration: 117.355944ms)","trace[640462892] 'applied index is now lower than readState.Index' (duration: 157.295µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:10:59.190548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.671226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T09:10:59.190581Z","caller":"traceutil/trace.go:171","msg":"trace[660191085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4767; }","duration":"117.763889ms","start":"2026-07-08T09:10:59.07281Z","end":"2026-07-08T09:10:59.190573Z","steps":["trace[660191085] 'agreement among raft nodes before linearized reading' (duration: 117.628944ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:10:59.190658Z","caller":"traceutil/trace.go:171","msg":"trace[2081342674] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"147.349689ms","start":"2026-07-08T09:10:59.043274Z","end":"2026-07-08T09:10:59.190624Z","steps":["trace[2081342674] 'process raft request' (duration: 146.996197ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:10.227575Z","caller":"traceutil/trace.go:171","msg":"trace[1209290893] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"155.75353ms","start":"2026-07-08T09:11:10.071801Z","end":"2026-07-08T09:11:10.227554Z","steps":["trace[1209290893] 'process raft request' (duration: 155.585354ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:38.698413Z","caller":"traceutil/trace.go:171","msg":"trace[823009296] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"115.564717ms","start":"2026-07-08T09:11:38.582821Z","end":"2026-07-08T09:11:38.698385Z","steps":["trace[823009296] 'process raft request' (duration: 115.519995ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:38.698445Z","caller":"traceutil/trace.go:171","msg":"trace[551611564] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"115.63593ms","start":"2026-07-08T09:11:38.58278Z","end":"2026-07-08T09:11:38.698416Z","steps":["trace[551611564] 'process raft request' (duration: 115.369569ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:38.698469Z","caller":"traceutil/trace.go:171","msg":"trace[2006905746] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"115.651101ms","start":"2026-07-08T09:11:38.582795Z","end":"2026-07-08T09:11:38.698446Z","steps":["trace[2006905746] 'process raft request' (duration: 115.505236ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:12:34.95547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-07-08T09:12:35.052707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"95.815322ms","hash":3951040307,"current-db-size-bytes":54243328,"current-db-size":"54 MB","current-db-size-in-use-bytes":29536256,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T09:12:35.052774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3951040307,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-07-08T09:15:53.948834Z","caller":"traceutil/trace.go:171","msg":"trace[429760404] transaction","detail":"{read_only:false; response_revision:6903; number_of_response:1; }","duration":"178.78248ms","start":"2026-07-08T09:15:53.770027Z","end":"2026-07-08T09:15:53.94881Z","steps":["trace[429760404] 'process raft request' (duration: 172.415221ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:15:53.949086Z","caller":"traceutil/trace.go:171","msg":"trace[1184173908] transaction","detail":"{read_only:false; response_revision:6904; number_of_response:1; }","duration":"178.407471ms","start":"2026-07-08T09:15:53.770668Z","end":"2026-07-08T09:15:53.949076Z","steps":["trace[1184173908] 'process raft request' (duration: 178.094226ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:15:53.949359Z","caller":"traceutil/trace.go:171","msg":"trace[831069999] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"155.613361ms","start":"2026-07-08T09:15:53.793709Z","end":"2026-07-08T09:15:53.949323Z","steps":["trace[831069999] 'process raft request' (duration: 155.330747ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:15:54.468563Z","caller":"traceutil/trace.go:171","msg":"trace[132903345] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"116.735429ms","start":"2026-07-08T09:15:54.351796Z","end":"2026-07-08T09:15:54.468532Z","steps":["trace[132903345] 'process raft request' (duration: 116.455284ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:23.303407Z","caller":"traceutil/trace.go:171","msg":"trace[1685763440] transaction","detail":"{read_only:false; response_revision:7354; number_of_response:1; }","duration":"150.062374ms","start":"2026-07-08T09:17:23.153311Z","end":"2026-07-08T09:17:23.303373Z","steps":["trace[1685763440] 'process raft request' (duration: 51.523779ms)","trace[1685763440] 'compare' (duration: 98.359453ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:17:23.307272Z","caller":"traceutil/trace.go:171","msg":"trace[1927010434] transaction","detail":"{read_only:false; response_revision:7355; number_of_response:1; }","duration":"150.840241ms","start":"2026-07-08T09:17:23.15642Z","end":"2026-07-08T09:17:23.30726Z","steps":["trace[1927010434] 'process raft request' (duration: 150.76583ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:34.962418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5613} {"level":"info","ts":"2026-07-08T09:17:35.087442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5613,"took":"123.032273ms","hash":1299127895,"current-db-size-bytes":54243328,"current-db-size":"54 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-08T09:17:35.087532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1299127895,"revision":5613,"compact-revision":2867}