{"level":"warn","ts":"2026-08-28T21:50:06.255751Z","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-28T21:50:06.25585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85: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://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-28T21:50:06.255926Z","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-28T21:50:06.255942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-08-28T21:50:06.255966Z","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-28T21:50:06.256949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-08-28T21:50:06.257125Z","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://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-28T21:50:06.261238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.844914ms"} {"level":"info","ts":"2026-08-28T21:50:06.268848Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-08-28T21:50:06.268962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T21:50:06.268998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-08-28T21:50:06.269023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T21:50:06.269126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-08-28T21:50:06.269188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-08-28T21:50:06.282868Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T21:50:06.286933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T21:50:06.289071Z","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-28T21:50:06.291895Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T21:50:06.292097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T21:50:06.292228Z","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-28T21:50:06.292301Z","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-28T21:50:06.292315Z","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-28T21:50:06.293946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-08-28T21:50:06.294135Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-08-28T21:50:06.295081Z","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-28T21:50:06.295601Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T21:50:06.295639Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-08-28T21:50:06.295709Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-08-28T21:50:06.295651Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T21:50:06.470614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T21:50:06.470712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T21:50:06.470744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-08-28T21:50:06.470767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-08-28T21:50:06.470781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-08-28T21:50:06.470795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-08-28T21:50:06.470812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-08-28T21:50:06.472186Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T21:50:06.473325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T21:50:06.473391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T21:50:06.473486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T21:50:06.473832Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T21:50:06.47391Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T21:50:06.473997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T21:50:06.474144Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T21:50:06.474192Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T21:50:06.476405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T21:50:06.476964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-08-28T21:50:49.291946Z","caller":"traceutil/trace.go:171","msg":"trace[267581263] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"165.888328ms","start":"2026-08-28T21:50:49.126018Z","end":"2026-08-28T21:50:49.291906Z","steps":["trace[267581263] 'process raft request' (duration: 165.641481ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:50:50.242203Z","caller":"traceutil/trace.go:171","msg":"trace[521606330] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"496.244203ms","start":"2026-08-28T21:50:49.745929Z","end":"2026-08-28T21:50:50.242173Z","steps":["trace[521606330] 'read index received' (duration: 495.414559ms)","trace[521606330] 'applied index is now lower than readState.Index' (duration: 828.474µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:50:50.24232Z","caller":"traceutil/trace.go:171","msg":"trace[1445314435] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"506.464883ms","start":"2026-08-28T21:50:49.735816Z","end":"2026-08-28T21:50:50.242281Z","steps":["trace[1445314435] 'process raft request' (duration: 505.801364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:50:50.242397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.418098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T21:50:50.24251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.883333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qs2sp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-28T21:50:50.242547Z","caller":"traceutil/trace.go:171","msg":"trace[585129343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"496.625804ms","start":"2026-08-28T21:50:49.745892Z","end":"2026-08-28T21:50:50.242518Z","steps":["trace[585129343] 'agreement among raft nodes before linearized reading' (duration: 496.385477ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:50:50.24255Z","caller":"traceutil/trace.go:171","msg":"trace[1564316115] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qs2sp; range_end:; response_count:1; response_revision:631; }","duration":"461.965736ms","start":"2026-08-28T21:50:49.780569Z","end":"2026-08-28T21:50:50.242535Z","steps":["trace[1564316115] 'agreement among raft nodes before linearized reading' (duration: 461.76488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:50:50.242595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:50:49.745878Z","time spent":"496.705977ms","remote":"127.0.0.1:37102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-28T21:50:50.242723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.493443ms","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-28T21:50:50.242762Z","caller":"traceutil/trace.go:171","msg":"trace[524526353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"390.561435ms","start":"2026-08-28T21:50:49.852191Z","end":"2026-08-28T21:50:50.242752Z","steps":["trace[524526353] 'agreement among raft nodes before linearized reading' (duration: 390.461302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:50:50.242787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:50:49.852178Z","time spent":"390.602557ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-28T21:50:50.242752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.253139ms","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-28T21:50:50.242759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.307704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-28T21:50:50.242601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:50:49.780557Z","time spent":"462.036127ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-qs2sp\" "} {"level":"info","ts":"2026-08-28T21:50:50.242857Z","caller":"traceutil/trace.go:171","msg":"trace[698576984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"284.401664ms","start":"2026-08-28T21:50:49.958433Z","end":"2026-08-28T21:50:50.242835Z","steps":["trace[698576984] 'agreement among raft nodes before linearized reading' (duration: 284.054313ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:50:50.242884Z","caller":"traceutil/trace.go:171","msg":"trace[117202474] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:631; }","duration":"313.510029ms","start":"2026-08-28T21:50:49.929341Z","end":"2026-08-28T21:50:50.242851Z","steps":["trace[117202474] 'agreement among raft nodes before linearized reading' (duration: 313.223421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:50:50.242942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:50:49.929331Z","time spent":"313.601221ms","remote":"127.0.0.1:37142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.85\" "} {"level":"warn","ts":"2026-08-28T21:50:50.243157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:50:49.735777Z","time spent":"506.66579ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T21:51:14.964404Z","caller":"traceutil/trace.go:171","msg":"trace[462020683] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"123.659457ms","start":"2026-08-28T21:51:14.8407Z","end":"2026-08-28T21:51:14.96436Z","steps":["trace[462020683] 'read index received' (duration: 123.404639ms)","trace[462020683] 'applied index is now lower than readState.Index' (duration: 253.658µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:51:14.964517Z","caller":"traceutil/trace.go:171","msg":"trace[1503345100] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"191.163562ms","start":"2026-08-28T21:51:14.773306Z","end":"2026-08-28T21:51:14.96447Z","steps":["trace[1503345100] 'process raft request' (duration: 190.898884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:51:14.96464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.914354ms","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-28T21:51:14.964739Z","caller":"traceutil/trace.go:171","msg":"trace[1736582112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"124.047608ms","start":"2026-08-28T21:51:14.840668Z","end":"2026-08-28T21:51:14.964716Z","steps":["trace[1736582112] 'agreement among raft nodes before linearized reading' (duration: 123.837862ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:51:15.124031Z","caller":"traceutil/trace.go:171","msg":"trace[69949288] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"153.094743ms","start":"2026-08-28T21:51:14.970902Z","end":"2026-08-28T21:51:15.123997Z","steps":["trace[69949288] 'process raft request' (duration: 126.342486ms)","trace[69949288] 'compare' (duration: 26.639063ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:51:15.151491Z","caller":"traceutil/trace.go:171","msg":"trace[466074010] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"178.015575ms","start":"2026-08-28T21:51:14.973455Z","end":"2026-08-28T21:51:15.15147Z","steps":["trace[466074010] 'process raft request' (duration: 177.879181ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:51:44.858187Z","caller":"traceutil/trace.go:171","msg":"trace[1414672699] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"200.832323ms","start":"2026-08-28T21:51:44.657319Z","end":"2026-08-28T21:51:44.858151Z","steps":["trace[1414672699] 'read index received' (duration: 200.551115ms)","trace[1414672699] 'applied index is now lower than readState.Index' (duration: 279.858µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:51:44.858278Z","caller":"traceutil/trace.go:171","msg":"trace[442989255] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"203.577505ms","start":"2026-08-28T21:51:44.654675Z","end":"2026-08-28T21:51:44.858253Z","steps":["trace[442989255] 'process raft request' (duration: 203.298317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:51:44.858435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.61785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T21:51:44.858478Z","caller":"traceutil/trace.go:171","msg":"trace[1531919782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"112.745893ms","start":"2026-08-28T21:51:44.745716Z","end":"2026-08-28T21:51:44.858462Z","steps":["trace[1531919782] 'agreement among raft nodes before linearized reading' (duration: 112.63531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:51:44.858542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.187395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-28T21:51:44.858652Z","caller":"traceutil/trace.go:171","msg":"trace[1336065025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"201.332598ms","start":"2026-08-28T21:51:44.657295Z","end":"2026-08-28T21:51:44.858627Z","steps":["trace[1336065025] 'agreement among raft nodes before linearized reading' (duration: 201.03867ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:52:06.409181Z","caller":"traceutil/trace.go:171","msg":"trace[2107990768] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"137.47866ms","start":"2026-08-28T21:52:06.271678Z","end":"2026-08-28T21:52:06.409157Z","steps":["trace[2107990768] 'process raft request' (duration: 137.310735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:52:32.123283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.013799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T21:52:32.123392Z","caller":"traceutil/trace.go:171","msg":"trace[1797075703] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"339.005514ms","start":"2026-08-28T21:52:31.784367Z","end":"2026-08-28T21:52:32.123372Z","steps":["trace[1797075703] 'process raft request' (duration: 55.681986ms)","trace[1797075703] 'compare' (duration: 282.889995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:52:32.123472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:52:31.784343Z","time spent":"339.101347ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T21:52:32.16872Z","caller":"traceutil/trace.go:171","msg":"trace[2076590011] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"256.092308ms","start":"2026-08-28T21:52:31.912606Z","end":"2026-08-28T21:52:32.168698Z","steps":["trace[2076590011] 'read index received' (duration: 255.976035ms)","trace[2076590011] 'applied index is now lower than readState.Index' (duration: 115.093µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:52:32.168765Z","caller":"traceutil/trace.go:171","msg":"trace[1082939474] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"383.88662ms","start":"2026-08-28T21:52:31.784848Z","end":"2026-08-28T21:52:32.168734Z","steps":["trace[1082939474] 'process raft request' (duration: 383.715704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:52:32.168871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.247583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T21:52:32.168961Z","caller":"traceutil/trace.go:171","msg":"trace[1441794682] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1897; }","duration":"256.380617ms","start":"2026-08-28T21:52:31.912561Z","end":"2026-08-28T21:52:32.168942Z","steps":["trace[1441794682] 'agreement among raft nodes before linearized reading' (duration: 256.227322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:52:32.169078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:52:31.784831Z","time spent":"384.000792ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T21:52:32.319263Z","caller":"traceutil/trace.go:171","msg":"trace[399569068] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"150.447885ms","start":"2026-08-28T21:52:32.168783Z","end":"2026-08-28T21:52:32.319231Z","steps":["trace[399569068] 'read index received' (duration: 91.734199ms)","trace[399569068] 'applied index is now lower than readState.Index' (duration: 58.712066ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:52:32.319299Z","caller":"traceutil/trace.go:171","msg":"trace[790692935] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"191.570458ms","start":"2026-08-28T21:52:32.127699Z","end":"2026-08-28T21:52:32.319269Z","steps":["trace[790692935] 'process raft request' (duration: 132.901363ms)","trace[790692935] 'compare' (duration: 58.49273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:52:32.319686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.812856ms","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":"warn","ts":"2026-08-28T21:52:32.319739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.236227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-28T21:52:32.319759Z","caller":"traceutil/trace.go:171","msg":"trace[145749478] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"194.102534ms","start":"2026-08-28T21:52:32.125637Z","end":"2026-08-28T21:52:32.319739Z","steps":["trace[145749478] 'agreement among raft nodes before linearized reading' (duration: 193.657041ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:52:32.319804Z","caller":"traceutil/trace.go:171","msg":"trace[181607310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1898; }","duration":"117.345911ms","start":"2026-08-28T21:52:32.202443Z","end":"2026-08-28T21:52:32.319788Z","steps":["trace[181607310] 'agreement among raft nodes before linearized reading' (duration: 116.958839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:52:32.320123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.580124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3985050-6e9a-4bec-8adf-834702f7388f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-28T21:52:32.320234Z","caller":"traceutil/trace.go:171","msg":"trace[354122355] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3985050-6e9a-4bec-8adf-834702f7388f; range_end:; response_count:1; response_revision:1898; }","duration":"212.740298ms","start":"2026-08-28T21:52:32.10747Z","end":"2026-08-28T21:52:32.32021Z","steps":["trace[354122355] 'agreement among raft nodes before linearized reading' (duration: 212.47631ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:52:32.49328Z","caller":"traceutil/trace.go:171","msg":"trace[1048099768] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"100.87498ms","start":"2026-08-28T21:52:32.392374Z","end":"2026-08-28T21:52:32.493249Z","steps":["trace[1048099768] 'read index received' (duration: 72.447805ms)","trace[1048099768] 'applied index is now lower than readState.Index' (duration: 28.425895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:52:32.493438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.013534ms","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-28T21:52:32.493473Z","caller":"traceutil/trace.go:171","msg":"trace[571796891] 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:1900; }","duration":"101.132858ms","start":"2026-08-28T21:52:32.392328Z","end":"2026-08-28T21:52:32.493461Z","steps":["trace[571796891] 'agreement among raft nodes before linearized reading' (duration: 101.020824ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:52:32.493486Z","caller":"traceutil/trace.go:171","msg":"trace[879015957] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"166.270106ms","start":"2026-08-28T21:52:32.327175Z","end":"2026-08-28T21:52:32.493445Z","steps":["trace[879015957] 'process raft request' (duration: 137.704177ms)","trace[879015957] 'compare' (duration: 28.228499ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:52:32.71588Z","caller":"traceutil/trace.go:171","msg":"trace[782734299] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"162.617847ms","start":"2026-08-28T21:52:32.553237Z","end":"2026-08-28T21:52:32.715855Z","steps":["trace[782734299] 'process raft request' (duration: 157.495445ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:52:46.476654Z","caller":"traceutil/trace.go:171","msg":"trace[196514537] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"159.757535ms","start":"2026-08-28T21:52:46.316874Z","end":"2026-08-28T21:52:46.476632Z","steps":["trace[196514537] 'read index received' (duration: 154.899152ms)","trace[196514537] 'applied index is now lower than readState.Index' (duration: 4.857593ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:52:46.476757Z","caller":"traceutil/trace.go:171","msg":"trace[978442049] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"211.684884ms","start":"2026-08-28T21:52:46.265063Z","end":"2026-08-28T21:52:46.476748Z","steps":["trace[978442049] 'process raft request' (duration: 206.649875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:52:46.476877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.994002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T21:52:46.476915Z","caller":"traceutil/trace.go:171","msg":"trace[482207232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2020; }","duration":"160.044923ms","start":"2026-08-28T21:52:46.316859Z","end":"2026-08-28T21:52:46.476904Z","steps":["trace[482207232] 'agreement among raft nodes before linearized reading' (duration: 159.973731ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:53:16.439714Z","caller":"traceutil/trace.go:171","msg":"trace[1259274775] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"186.102591ms","start":"2026-08-28T21:53:16.253582Z","end":"2026-08-28T21:53:16.439684Z","steps":["trace[1259274775] 'process raft request' (duration: 185.945286ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:53:16.577635Z","caller":"traceutil/trace.go:171","msg":"trace[1965074103] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"259.363737ms","start":"2026-08-28T21:53:16.31824Z","end":"2026-08-28T21:53:16.577604Z","steps":["trace[1965074103] 'process raft request' (duration: 259.309516ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:53:16.577902Z","caller":"traceutil/trace.go:171","msg":"trace[1066497486] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"305.513827ms","start":"2026-08-28T21:53:16.272339Z","end":"2026-08-28T21:53:16.577853Z","steps":["trace[1066497486] 'process raft request' (duration: 282.597932ms)","trace[1066497486] 'compare' (duration: 22.43447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:53:16.578165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T21:53:16.272318Z","time spent":"305.733704ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T21:53:16.816198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.966984ms","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-28T21:53:16.816262Z","caller":"traceutil/trace.go:171","msg":"trace[1577321126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2198; }","duration":"135.065407ms","start":"2026-08-28T21:53:16.68118Z","end":"2026-08-28T21:53:16.816245Z","steps":["trace[1577321126] 'range keys from in-memory index tree' (duration: 134.819421ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:53:22.712527Z","caller":"traceutil/trace.go:171","msg":"trace[1233130382] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"115.236854ms","start":"2026-08-28T21:53:22.597247Z","end":"2026-08-28T21:53:22.712484Z","steps":["trace[1233130382] 'process raft request' (duration: 78.453671ms)","trace[1233130382] 'compare' (duration: 36.499665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:53:22.980812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.217067ms","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-28T21:53:22.980919Z","caller":"traceutil/trace.go:171","msg":"trace[961715752] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2231; }","duration":"125.348461ms","start":"2026-08-28T21:53:22.855548Z","end":"2026-08-28T21:53:22.980896Z","steps":["trace[961715752] 'range keys from in-memory index tree' (duration: 125.029922ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:53:22.995161Z","caller":"traceutil/trace.go:171","msg":"trace[1511263456] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"103.939651ms","start":"2026-08-28T21:53:22.891198Z","end":"2026-08-28T21:53:22.995137Z","steps":["trace[1511263456] 'process raft request' (duration: 103.810057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T21:55:14.93424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.270289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T21:55:14.934338Z","caller":"traceutil/trace.go:171","msg":"trace[1550110357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3010; }","duration":"189.424383ms","start":"2026-08-28T21:55:14.744891Z","end":"2026-08-28T21:55:14.934316Z","steps":["trace[1550110357] 'range keys from in-memory index tree' (duration: 189.138155ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T21:55:46.360219Z","caller":"traceutil/trace.go:171","msg":"trace[829061728] transaction","detail":"{read_only:false; response_revision:3186; number_of_response:1; }","duration":"129.091442ms","start":"2026-08-28T21:55:46.231095Z","end":"2026-08-28T21:55:46.360186Z","steps":["trace[829061728] 'process raft request' (duration: 53.557793ms)","trace[829061728] 'compare' (duration: 74.949532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T21:55:51.826373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.900491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T21:55:51.826568Z","caller":"traceutil/trace.go:171","msg":"trace[1467989002] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"258.436531ms","start":"2026-08-28T21:55:51.568111Z","end":"2026-08-28T21:55:51.826548Z","steps":["trace[1467989002] 'process raft request' (duration: 89.258462ms)","trace[1467989002] 'compare' (duration: 168.51721ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T21:58:31.083042Z","caller":"traceutil/trace.go:171","msg":"trace[1370089266] transaction","detail":"{read_only:false; response_revision:4213; number_of_response:1; }","duration":"108.742864ms","start":"2026-08-28T21:58:30.974258Z","end":"2026-08-28T21:58:31.083001Z","steps":["trace[1370089266] 'process raft request' (duration: 108.617371ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:06.702569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2930} {"level":"info","ts":"2026-08-28T22:00:06.783978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2930,"took":"80.159058ms","hash":186536750,"current-db-size-bytes":43667456,"current-db-size":"44 MB","current-db-size-in-use-bytes":18571264,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T22:00:06.784128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":186536750,"revision":2930,"compact-revision":-1} {"level":"info","ts":"2026-08-28T22:00:41.060161Z","caller":"traceutil/trace.go:171","msg":"trace[1323062487] linearizableReadLoop","detail":"{readStateIndex:5856; appliedIndex:5855; }","duration":"240.439127ms","start":"2026-08-28T22:00:40.819694Z","end":"2026-08-28T22:00:41.060133Z","steps":["trace[1323062487] 'read index received' (duration: 240.21155ms)","trace[1323062487] 'applied index is now lower than readState.Index' (duration: 226.187µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:00:41.060183Z","caller":"traceutil/trace.go:171","msg":"trace[887468501] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"245.63167ms","start":"2026-08-28T22:00:40.814518Z","end":"2026-08-28T22:00:41.06015Z","steps":["trace[887468501] 'process raft request' (duration: 245.465905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:00:41.060371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.653153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f297473a-3f9f-48c8-8375-59f19806fd4a\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-08-28T22:00:41.060439Z","caller":"traceutil/trace.go:171","msg":"trace[94174136] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f297473a-3f9f-48c8-8375-59f19806fd4a; range_end:; response_count:1; response_revision:5643; }","duration":"240.750266ms","start":"2026-08-28T22:00:40.81966Z","end":"2026-08-28T22:00:41.06041Z","steps":["trace[94174136] 'agreement among raft nodes before linearized reading' (duration: 240.588112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:00:41.06044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.272391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T22:00:41.060586Z","caller":"traceutil/trace.go:171","msg":"trace[38334674] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5643; }","duration":"223.529959ms","start":"2026-08-28T22:00:40.837045Z","end":"2026-08-28T22:00:41.060575Z","steps":["trace[38334674] 'agreement among raft nodes before linearized reading' (duration: 223.241311ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:41.344786Z","caller":"traceutil/trace.go:171","msg":"trace[1915050191] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"117.704069ms","start":"2026-08-28T22:00:41.227057Z","end":"2026-08-28T22:00:41.344761Z","steps":["trace[1915050191] 'process raft request' (duration: 117.655618ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:41.344832Z","caller":"traceutil/trace.go:171","msg":"trace[1794907394] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"120.77347ms","start":"2026-08-28T22:00:41.224032Z","end":"2026-08-28T22:00:41.344806Z","steps":["trace[1794907394] 'process raft request' (duration: 120.556983ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.114098Z","caller":"traceutil/trace.go:171","msg":"trace[1989661915] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"117.655027ms","start":"2026-08-28T22:00:49.996418Z","end":"2026-08-28T22:00:50.114073Z","steps":["trace[1989661915] 'process raft request' (duration: 117.439511ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.114353Z","caller":"traceutil/trace.go:171","msg":"trace[1961661086] transaction","detail":"{read_only:false; response_revision:5828; number_of_response:1; }","duration":"117.281386ms","start":"2026-08-28T22:00:49.997051Z","end":"2026-08-28T22:00:50.114333Z","steps":["trace[1961661086] 'process raft request' (duration: 116.970517ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.114678Z","caller":"traceutil/trace.go:171","msg":"trace[381346644] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"117.477172ms","start":"2026-08-28T22:00:49.997166Z","end":"2026-08-28T22:00:50.114643Z","steps":["trace[381346644] 'process raft request' (duration: 117.080331ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.114929Z","caller":"traceutil/trace.go:171","msg":"trace[1877037685] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"116.100352ms","start":"2026-08-28T22:00:49.998778Z","end":"2026-08-28T22:00:50.114878Z","steps":["trace[1877037685] 'process raft request' (duration: 115.745252ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.1149Z","caller":"traceutil/trace.go:171","msg":"trace[1791098070] linearizableReadLoop","detail":"{readStateIndex:6049; appliedIndex:6045; }","duration":"113.588218ms","start":"2026-08-28T22:00:50.001276Z","end":"2026-08-28T22:00:50.114864Z","steps":["trace[1791098070] 'read index received' (duration: 112.682871ms)","trace[1791098070] 'applied index is now lower than readState.Index' (duration: 901.677µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:00:50.115159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.08378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62961"} {"level":"info","ts":"2026-08-28T22:00:50.115209Z","caller":"traceutil/trace.go:171","msg":"trace[1292526021] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5830; }","duration":"120.169932ms","start":"2026-08-28T22:00:49.995027Z","end":"2026-08-28T22:00:50.115197Z","steps":["trace[1292526021] 'agreement among raft nodes before linearized reading' (duration: 119.892403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:00:50.115302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.46628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2484a205-dc7a-4b1b-aa8f-7903c817d95d\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-28T22:00:50.115373Z","caller":"traceutil/trace.go:171","msg":"trace[2117850585] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2484a205-dc7a-4b1b-aa8f-7903c817d95d; range_end:; response_count:1; response_revision:5830; }","duration":"122.567073ms","start":"2026-08-28T22:00:49.992783Z","end":"2026-08-28T22:00:50.11535Z","steps":["trace[2117850585] 'agreement among raft nodes before linearized reading' (duration: 122.345217ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:00:50.11599Z","caller":"traceutil/trace.go:171","msg":"trace[1517928864] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"113.399182ms","start":"2026-08-28T22:00:50.002576Z","end":"2026-08-28T22:00:50.115975Z","steps":["trace[1517928864] 'process raft request' (duration: 112.748273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:00:56.806157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.949463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T22:00:56.80656Z","caller":"traceutil/trace.go:171","msg":"trace[2081411926] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"130.222249ms","start":"2026-08-28T22:00:56.676279Z","end":"2026-08-28T22:00:56.806501Z","steps":["trace[2081411926] 'compare' (duration: 123.801869ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:01:06.098132Z","caller":"traceutil/trace.go:171","msg":"trace[1468706110] linearizableReadLoop","detail":"{readStateIndex:6310; appliedIndex:6309; }","duration":"113.356241ms","start":"2026-08-28T22:01:05.984747Z","end":"2026-08-28T22:01:06.098104Z","steps":["trace[1468706110] 'read index received' (duration: 113.207357ms)","trace[1468706110] 'applied index is now lower than readState.Index' (duration: 147.754µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:01:06.098188Z","caller":"traceutil/trace.go:171","msg":"trace[1754056618] transaction","detail":"{read_only:false; response_revision:6087; number_of_response:1; }","duration":"147.759085ms","start":"2026-08-28T22:01:05.950396Z","end":"2026-08-28T22:01:06.098155Z","steps":["trace[1754056618] 'process raft request' (duration: 147.524479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:01:06.098362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.559707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T22:01:06.098432Z","caller":"traceutil/trace.go:171","msg":"trace[210028911] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:6087; }","duration":"113.696111ms","start":"2026-08-28T22:01:05.984719Z","end":"2026-08-28T22:01:06.098415Z","steps":["trace[210028911] 'agreement among raft nodes before linearized reading' (duration: 113.516886ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:05:06.712399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4828} {"level":"info","ts":"2026-08-28T22:05:06.77292Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4828,"took":"58.389856ms","hash":392633114,"current-db-size-bytes":43667456,"current-db-size":"44 MB","current-db-size-in-use-bytes":38903808,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-08-28T22:05:06.773111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":392633114,"revision":4828,"compact-revision":2930} {"level":"info","ts":"2026-08-28T22:07:33.457322Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002485.wal"} {"level":"info","ts":"2026-08-28T22:07:34.694849Z","caller":"traceutil/trace.go:171","msg":"trace[1334790286] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"421.354101ms","start":"2026-08-28T22:07:34.273449Z","end":"2026-08-28T22:07:34.694803Z","steps":["trace[1334790286] 'process raft request' (duration: 421.079113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.695131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:34.273429Z","time spent":"421.530246ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:07:34.695968Z","caller":"traceutil/trace.go:171","msg":"trace[505719992] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"268.066013ms","start":"2026-08-28T22:07:34.427887Z","end":"2026-08-28T22:07:34.695953Z","steps":["trace[505719992] 'process raft request' (duration: 267.97071ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:07:34.696049Z","caller":"traceutil/trace.go:171","msg":"trace[972752569] linearizableReadLoop","detail":"{readStateIndex:9355; appliedIndex:9353; }","duration":"420.62473ms","start":"2026-08-28T22:07:34.275393Z","end":"2026-08-28T22:07:34.696018Z","steps":["trace[972752569] 'read index received' (duration: 419.169347ms)","trace[972752569] 'applied index is now lower than readState.Index' (duration: 1.454143ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:07:34.696157Z","caller":"traceutil/trace.go:171","msg":"trace[1951425900] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"422.590467ms","start":"2026-08-28T22:07:34.273553Z","end":"2026-08-28T22:07:34.696144Z","steps":["trace[1951425900] 'process raft request' (duration: 422.230957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.696232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:34.273523Z","time spent":"422.66194ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:07:34.696249Z","caller":"traceutil/trace.go:171","msg":"trace[941447871] transaction","detail":"{read_only:false; response_revision:9038; number_of_response:1; }","duration":"115.135544ms","start":"2026-08-28T22:07:34.5811Z","end":"2026-08-28T22:07:34.696236Z","steps":["trace[941447871] 'process raft request' (duration: 114.806894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.696658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.142639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-28T22:07:34.696775Z","caller":"traceutil/trace.go:171","msg":"trace[11415778] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9038; }","duration":"412.328784ms","start":"2026-08-28T22:07:34.284423Z","end":"2026-08-28T22:07:34.696752Z","steps":["trace[11415778] 'agreement among raft nodes before linearized reading' (duration: 412.008575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.696761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.790525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6f97785cc7-rj489.18d017753f6494b3\" ","response":"range_response_count:1 size:1134"} {"level":"warn","ts":"2026-08-28T22:07:34.696793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.375391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-28T22:07:34.69685Z","caller":"traceutil/trace.go:171","msg":"trace[1210968049] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6f97785cc7-rj489.18d017753f6494b3; range_end:; response_count:1; response_revision:9038; }","duration":"286.94948ms","start":"2026-08-28T22:07:34.409876Z","end":"2026-08-28T22:07:34.696826Z","steps":["trace[1210968049] 'agreement among raft nodes before linearized reading' (duration: 286.686562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.696894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:34.284399Z","time spent":"412.480249ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-28T22:07:34.696916Z","caller":"traceutil/trace.go:171","msg":"trace[1694721853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9038; }","duration":"421.528776ms","start":"2026-08-28T22:07:34.275356Z","end":"2026-08-28T22:07:34.696885Z","steps":["trace[1694721853] 'agreement among raft nodes before linearized reading' (duration: 421.155655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:34.696982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:34.275344Z","time spent":"421.621429ms","remote":"127.0.0.1:37356","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-08-28T22:07:46.060927Z","caller":"traceutil/trace.go:171","msg":"trace[2047981300] linearizableReadLoop","detail":"{readStateIndex:9417; appliedIndex:9416; }","duration":"119.03835ms","start":"2026-08-28T22:07:45.941847Z","end":"2026-08-28T22:07:46.060886Z","steps":["trace[2047981300] 'read index received' (duration: 118.71489ms)","trace[2047981300] 'applied index is now lower than readState.Index' (duration: 321.56µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:07:46.061052Z","caller":"traceutil/trace.go:171","msg":"trace[1248865186] transaction","detail":"{read_only:false; response_revision:9095; number_of_response:1; }","duration":"157.547105ms","start":"2026-08-28T22:07:45.903446Z","end":"2026-08-28T22:07:46.060993Z","steps":["trace[1248865186] 'process raft request' (duration: 157.113912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:46.061246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.38278ms","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-28T22:07:46.061338Z","caller":"traceutil/trace.go:171","msg":"trace[1227857574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9095; }","duration":"119.491903ms","start":"2026-08-28T22:07:45.941831Z","end":"2026-08-28T22:07:46.061323Z","steps":["trace[1227857574] 'agreement among raft nodes before linearized reading' (duration: 119.239595ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:07:46.345902Z","caller":"traceutil/trace.go:171","msg":"trace[727588858] transaction","detail":"{read_only:false; response_revision:9096; number_of_response:1; }","duration":"321.371955ms","start":"2026-08-28T22:07:46.024498Z","end":"2026-08-28T22:07:46.34587Z","steps":["trace[727588858] 'process raft request' (duration: 298.863691ms)","trace[727588858] 'compare' (duration: 22.04464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:07:46.346059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:46.024483Z","time spent":"321.492048ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:07:46.368913Z","caller":"traceutil/trace.go:171","msg":"trace[172284078] transaction","detail":"{read_only:false; response_revision:9097; number_of_response:1; }","duration":"302.122257ms","start":"2026-08-28T22:07:46.066765Z","end":"2026-08-28T22:07:46.368887Z","steps":["trace[172284078] 'process raft request' (duration: 301.949132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:07:46.369141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:07:46.066742Z","time spent":"302.246041ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:07:46.369196Z","caller":"traceutil/trace.go:171","msg":"trace[1234957291] transaction","detail":"{read_only:false; response_revision:9098; number_of_response:1; }","duration":"268.038703ms","start":"2026-08-28T22:07:46.101121Z","end":"2026-08-28T22:07:46.36916Z","steps":["trace[1234957291] 'process raft request' (duration: 267.713473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:18.739712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.995004ms","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-28T22:08:18.739807Z","caller":"traceutil/trace.go:171","msg":"trace[1080885156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9324; }","duration":"108.19363ms","start":"2026-08-28T22:08:18.631592Z","end":"2026-08-28T22:08:18.739786Z","steps":["trace[1080885156] 'range keys from in-memory index tree' (duration: 107.789338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:19.119033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.631517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T22:08:19.119152Z","caller":"traceutil/trace.go:171","msg":"trace[1300446703] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:9325; }","duration":"273.786232ms","start":"2026-08-28T22:08:18.845337Z","end":"2026-08-28T22:08:19.119123Z","steps":["trace[1300446703] 'count revisions from in-memory index tree' (duration: 273.472273ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:08:19.119343Z","caller":"traceutil/trace.go:171","msg":"trace[753987544] transaction","detail":"{read_only:false; response_revision:9326; number_of_response:1; }","duration":"203.783758ms","start":"2026-08-28T22:08:18.915532Z","end":"2026-08-28T22:08:19.119315Z","steps":["trace[753987544] 'process raft request' (duration: 166.412016ms)","trace[753987544] 'compare' (duration: 37.062463ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:08:19.254698Z","caller":"traceutil/trace.go:171","msg":"trace[325284047] transaction","detail":"{read_only:false; response_revision:9328; number_of_response:1; }","duration":"129.469178ms","start":"2026-08-28T22:08:19.1252Z","end":"2026-08-28T22:08:19.25467Z","steps":["trace[325284047] 'process raft request' (duration: 129.22448ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:08:19.255059Z","caller":"traceutil/trace.go:171","msg":"trace[1328659906] transaction","detail":"{read_only:false; response_revision:9329; number_of_response:1; }","duration":"129.760716ms","start":"2026-08-28T22:08:19.125256Z","end":"2026-08-28T22:08:19.255017Z","steps":["trace[1328659906] 'process raft request' (duration: 129.362924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:27.639396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.79529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T22:08:27.639671Z","caller":"traceutil/trace.go:171","msg":"trace[1530572940] transaction","detail":"{read_only:false; response_revision:9375; number_of_response:1; }","duration":"180.930214ms","start":"2026-08-28T22:08:27.458713Z","end":"2026-08-28T22:08:27.639643Z","steps":["trace[1530572940] 'process raft request' (duration: 38.774563ms)","trace[1530572940] 'compare' (duration: 141.292916ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:08:28.505619Z","caller":"traceutil/trace.go:171","msg":"trace[2070060281] transaction","detail":"{read_only:false; response_revision:9376; number_of_response:1; }","duration":"191.574918ms","start":"2026-08-28T22:08:28.314013Z","end":"2026-08-28T22:08:28.505587Z","steps":["trace[2070060281] 'process raft request' (duration: 191.31053ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:08:33.549242Z","caller":"traceutil/trace.go:171","msg":"trace[883288179] linearizableReadLoop","detail":"{readStateIndex:9747; appliedIndex:9745; }","duration":"219.720978ms","start":"2026-08-28T22:08:33.329499Z","end":"2026-08-28T22:08:33.54922Z","steps":["trace[883288179] 'read index received' (duration: 36.672311ms)","trace[883288179] 'applied index is now lower than readState.Index' (duration: 183.047437ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:08:33.549373Z","caller":"traceutil/trace.go:171","msg":"trace[1005419552] transaction","detail":"{read_only:false; response_revision:9413; number_of_response:1; }","duration":"239.050838ms","start":"2026-08-28T22:08:33.310309Z","end":"2026-08-28T22:08:33.549359Z","steps":["trace[1005419552] 'process raft request' (duration: 224.903061ms)","trace[1005419552] 'compare' (duration: 13.473967ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:08:33.549761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.251863ms","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-08-28T22:08:33.549823Z","caller":"traceutil/trace.go:171","msg":"trace[602077743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9413; }","duration":"220.374847ms","start":"2026-08-28T22:08:33.329438Z","end":"2026-08-28T22:08:33.549813Z","steps":["trace[602077743] 'agreement among raft nodes before linearized reading' (duration: 220.174402ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:08:33.816942Z","caller":"traceutil/trace.go:171","msg":"trace[1623016940] transaction","detail":"{read_only:false; response_revision:9414; number_of_response:1; }","duration":"385.683271ms","start":"2026-08-28T22:08:33.431228Z","end":"2026-08-28T22:08:33.816911Z","steps":["trace[1623016940] 'process raft request' (duration: 385.250569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:33.817154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:08:33.431189Z","time spent":"385.863987ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415350,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:08:33.872344Z","caller":"traceutil/trace.go:171","msg":"trace[1609839472] transaction","detail":"{read_only:false; response_revision:9416; number_of_response:1; }","duration":"317.720647ms","start":"2026-08-28T22:08:33.55459Z","end":"2026-08-28T22:08:33.872311Z","steps":["trace[1609839472] 'process raft request' (duration: 317.621494ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:08:33.872527Z","caller":"traceutil/trace.go:171","msg":"trace[417955433] linearizableReadLoop","detail":"{readStateIndex:9749; appliedIndex:9747; }","duration":"323.163148ms","start":"2026-08-28T22:08:33.549334Z","end":"2026-08-28T22:08:33.872497Z","steps":["trace[417955433] 'read index received' (duration: 267.011723ms)","trace[417955433] 'applied index is now lower than readState.Index' (duration: 56.148965ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:08:33.872545Z","caller":"traceutil/trace.go:171","msg":"trace[1024782396] transaction","detail":"{read_only:false; response_revision:9417; number_of_response:1; }","duration":"212.221036ms","start":"2026-08-28T22:08:33.660288Z","end":"2026-08-28T22:08:33.872509Z","steps":["trace[1024782396] 'process raft request' (duration: 211.979659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:33.872582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:08:33.554555Z","time spent":"317.911943ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:08:33.872352Z","caller":"traceutil/trace.go:171","msg":"trace[1713709366] transaction","detail":"{read_only:false; response_revision:9415; number_of_response:1; }","duration":"323.537848ms","start":"2026-08-28T22:08:33.548774Z","end":"2026-08-28T22:08:33.872312Z","steps":["trace[1713709366] 'process raft request' (duration: 323.289661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:33.872863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.113733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-28T22:08:33.872907Z","caller":"traceutil/trace.go:171","msg":"trace[2114550339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9417; }","duration":"542.278798ms","start":"2026-08-28T22:08:33.330616Z","end":"2026-08-28T22:08:33.872895Z","steps":["trace[2114550339] 'agreement among raft nodes before linearized reading' (duration: 541.972029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:33.872853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:08:33.54873Z","time spent":"324.020722ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T22:08:33.872941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:08:33.330575Z","time spent":"542.35871ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-28T22:08:33.87325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.404022ms","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-28T22:08:33.873432Z","caller":"traceutil/trace.go:171","msg":"trace[319121406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9417; }","duration":"542.72384ms","start":"2026-08-28T22:08:33.330634Z","end":"2026-08-28T22:08:33.873358Z","steps":["trace[319121406] 'agreement among raft nodes before linearized reading' (duration: 541.929827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:08:33.87356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:08:33.330597Z","time spent":"542.916856ms","remote":"127.0.0.1:37356","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":"info","ts":"2026-08-28T22:08:57.508503Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T22:08:57.518221Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-28T22:08:57.5184Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-28T22:09:46.453744Z","caller":"traceutil/trace.go:171","msg":"trace[1508277757] transaction","detail":"{read_only:false; response_revision:10174; number_of_response:1; }","duration":"127.692999ms","start":"2026-08-28T22:09:46.326017Z","end":"2026-08-28T22:09:46.45371Z","steps":["trace[1508277757] 'process raft request' (duration: 103.205119ms)","trace[1508277757] 'compare' (duration: 24.376047ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:09:46.47272Z","caller":"traceutil/trace.go:171","msg":"trace[1480267960] transaction","detail":"{read_only:false; response_revision:10175; number_of_response:1; }","duration":"144.119293ms","start":"2026-08-28T22:09:46.328565Z","end":"2026-08-28T22:09:46.472685Z","steps":["trace[1480267960] 'process raft request' (duration: 143.980539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:10:00.476771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.60963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T22:10:00.477051Z","caller":"traceutil/trace.go:171","msg":"trace[223600689] linearizableReadLoop","detail":"{readStateIndex:10612; appliedIndex:10610; }","duration":"157.443434ms","start":"2026-08-28T22:10:00.319564Z","end":"2026-08-28T22:10:00.477008Z","steps":["trace[223600689] 'read index received' (duration: 10.057876ms)","trace[223600689] 'applied index is now lower than readState.Index' (duration: 147.384558ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:10:00.477133Z","caller":"traceutil/trace.go:171","msg":"trace[1254432073] transaction","detail":"{read_only:false; response_revision:10252; number_of_response:1; }","duration":"255.367806ms","start":"2026-08-28T22:10:00.221709Z","end":"2026-08-28T22:10:00.477077Z","steps":["trace[1254432073] 'process raft request' (duration: 255.184301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:10:00.47725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.692941ms","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-08-28T22:10:00.477173Z","caller":"traceutil/trace.go:171","msg":"trace[1082847606] transaction","detail":"{read_only:false; response_revision:10251; number_of_response:1; }","duration":"409.244586ms","start":"2026-08-28T22:10:00.067749Z","end":"2026-08-28T22:10:00.476993Z","steps":["trace[1082847606] 'process raft request' (duration: 93.327717ms)","trace[1082847606] 'compare' (duration: 315.189928ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:10:00.477294Z","caller":"traceutil/trace.go:171","msg":"trace[1006315708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10252; }","duration":"157.782244ms","start":"2026-08-28T22:10:00.319499Z","end":"2026-08-28T22:10:00.477281Z","steps":["trace[1006315708] 'agreement among raft nodes before linearized reading' (duration: 157.609659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:10:00.477538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:10:00.067722Z","time spent":"409.621487ms","remote":"127.0.0.1:37142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:10:06.729616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7861} {"level":"info","ts":"2026-08-28T22:10:06.829534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7861,"took":"97.735237ms","hash":655344419,"current-db-size-bytes":43667456,"current-db-size":"44 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-28T22:10:06.829693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":655344419,"revision":7861,"compact-revision":4828} {"level":"info","ts":"2026-08-28T22:15:06.737566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10309} {"level":"info","ts":"2026-08-28T22:15:06.83158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10309,"took":"91.074533ms","hash":4091874577,"current-db-size-bytes":43667456,"current-db-size":"44 MB","current-db-size-in-use-bytes":25063424,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-28T22:15:06.831653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091874577,"revision":10309,"compact-revision":7861} {"level":"info","ts":"2026-08-28T22:15:53.213869Z","caller":"traceutil/trace.go:171","msg":"trace[1374752381] transaction","detail":"{read_only:false; response_revision:12761; number_of_response:1; }","duration":"230.566795ms","start":"2026-08-28T22:15:52.983275Z","end":"2026-08-28T22:15:53.213842Z","steps":["trace[1374752381] 'process raft request' (duration: 230.40206ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:15:53.228131Z","caller":"traceutil/trace.go:171","msg":"trace[1471916659] transaction","detail":"{read_only:false; response_revision:12762; number_of_response:1; }","duration":"240.421056ms","start":"2026-08-28T22:15:52.98769Z","end":"2026-08-28T22:15:53.228111Z","steps":["trace[1471916659] 'process raft request' (duration: 240.332273ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:20:06.747779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12312} {"level":"info","ts":"2026-08-28T22:20:06.83496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12312,"took":"83.220548ms","hash":4055734211,"current-db-size-bytes":43667456,"current-db-size":"44 MB","current-db-size-in-use-bytes":27013120,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-28T22:20:06.835027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055734211,"revision":12312,"compact-revision":10309} {"level":"warn","ts":"2026-08-28T22:22:41.88499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.924142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T22:22:41.885142Z","caller":"traceutil/trace.go:171","msg":"trace[290542579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15454; }","duration":"139.088987ms","start":"2026-08-28T22:22:41.746027Z","end":"2026-08-28T22:22:41.885116Z","steps":["trace[290542579] 'range keys from in-memory index tree' (duration: 138.774658ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:22:45.172164Z","caller":"traceutil/trace.go:171","msg":"trace[50595833] linearizableReadLoop","detail":"{readStateIndex:16008; appliedIndex:16007; }","duration":"121.695623ms","start":"2026-08-28T22:22:45.050429Z","end":"2026-08-28T22:22:45.172124Z","steps":["trace[50595833] 'read index received' (duration: 121.419845ms)","trace[50595833] 'applied index is now lower than readState.Index' (duration: 273.328µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:22:45.172218Z","caller":"traceutil/trace.go:171","msg":"trace[1091655332] transaction","detail":"{read_only:false; response_revision:15471; number_of_response:1; }","duration":"137.386247ms","start":"2026-08-28T22:22:45.034801Z","end":"2026-08-28T22:22:45.172187Z","steps":["trace[1091655332] 'process raft request' (duration: 137.15081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:22:45.172407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.965361ms","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-28T22:22:45.172451Z","caller":"traceutil/trace.go:171","msg":"trace[857936317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15471; }","duration":"122.081185ms","start":"2026-08-28T22:22:45.050359Z","end":"2026-08-28T22:22:45.17244Z","steps":["trace[857936317] 'agreement among raft nodes before linearized reading' (duration: 121.89025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:23:24.740081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.297871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T22:23:24.740203Z","caller":"traceutil/trace.go:171","msg":"trace[563108993] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:15686; }","duration":"103.507517ms","start":"2026-08-28T22:23:24.636669Z","end":"2026-08-28T22:23:24.740176Z","steps":["trace[563108993] 'count revisions from in-memory index tree' (duration: 103.081404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:23:25.710051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.555305ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-28T22:23:25.710177Z","caller":"traceutil/trace.go:171","msg":"trace[1467593908] linearizableReadLoop","detail":"{readStateIndex:16238; appliedIndex:16237; }","duration":"286.623314ms","start":"2026-08-28T22:23:25.423534Z","end":"2026-08-28T22:23:25.710157Z","steps":["trace[1467593908] 'read index received' (duration: 90.928245ms)","trace[1467593908] 'applied index is now lower than readState.Index' (duration: 195.693029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:23:25.710412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.865791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-28T22:23:25.710452Z","caller":"traceutil/trace.go:171","msg":"trace[712004280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15690; }","duration":"286.943873ms","start":"2026-08-28T22:23:25.423494Z","end":"2026-08-28T22:23:25.710438Z","steps":["trace[712004280] 'agreement among raft nodes before linearized reading' (duration: 286.765798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:23:25.710518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.678896ms","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-28T22:23:25.710629Z","caller":"traceutil/trace.go:171","msg":"trace[521207915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15690; }","duration":"286.904972ms","start":"2026-08-28T22:23:25.423697Z","end":"2026-08-28T22:23:25.710602Z","steps":["trace[521207915] 'agreement among raft nodes before linearized reading' (duration: 286.599753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:23:25.710837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.031474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T22:23:25.710983Z","caller":"traceutil/trace.go:171","msg":"trace[161680456] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:15690; }","duration":"269.282551ms","start":"2026-08-28T22:23:25.441665Z","end":"2026-08-28T22:23:25.710948Z","steps":["trace[161680456] 'agreement among raft nodes before linearized reading' (duration: 268.996813ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:23:36.381819Z","caller":"traceutil/trace.go:171","msg":"trace[1188581329] linearizableReadLoop","detail":"{readStateIndex:16416; appliedIndex:16415; }","duration":"102.342392ms","start":"2026-08-28T22:23:36.279442Z","end":"2026-08-28T22:23:36.381784Z","steps":["trace[1188581329] 'read index received' (duration: 102.211949ms)","trace[1188581329] 'applied index is now lower than readState.Index' (duration: 129.003µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:23:36.382108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.639471ms","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-28T22:23:36.38215Z","caller":"traceutil/trace.go:171","msg":"trace[1896285347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15865; }","duration":"102.763785ms","start":"2026-08-28T22:23:36.279368Z","end":"2026-08-28T22:23:36.382132Z","steps":["trace[1896285347] 'agreement among raft nodes before linearized reading' (duration: 102.553229ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:23:36.38217Z","caller":"traceutil/trace.go:171","msg":"trace[1278762201] transaction","detail":"{read_only:false; response_revision:15865; number_of_response:1; }","duration":"362.335549ms","start":"2026-08-28T22:23:36.019774Z","end":"2026-08-28T22:23:36.382109Z","steps":["trace[1278762201] 'process raft request' (duration: 361.780893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:23:36.382468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:23:36.019748Z","time spent":"362.591457ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:23:36.538087Z","caller":"traceutil/trace.go:171","msg":"trace[1877181835] transaction","detail":"{read_only:false; response_revision:15867; number_of_response:1; }","duration":"200.15355ms","start":"2026-08-28T22:23:36.337897Z","end":"2026-08-28T22:23:36.53805Z","steps":["trace[1877181835] 'process raft request' (duration: 200.074447ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:23:36.538572Z","caller":"traceutil/trace.go:171","msg":"trace[1414464469] transaction","detail":"{read_only:false; response_revision:15866; number_of_response:1; }","duration":"220.529532ms","start":"2026-08-28T22:23:36.318029Z","end":"2026-08-28T22:23:36.538559Z","steps":["trace[1414464469] 'process raft request' (duration: 219.426589ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:23:36.54152Z","caller":"traceutil/trace.go:171","msg":"trace[405672724] transaction","detail":"{read_only:false; response_revision:15868; number_of_response:1; }","duration":"152.584986ms","start":"2026-08-28T22:23:36.388888Z","end":"2026-08-28T22:23:36.541473Z","steps":["trace[405672724] 'process raft request' (duration: 152.319858ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:23:36.541612Z","caller":"traceutil/trace.go:171","msg":"trace[294132644] transaction","detail":"{read_only:false; response_revision:15869; number_of_response:1; }","duration":"149.979528ms","start":"2026-08-28T22:23:36.391586Z","end":"2026-08-28T22:23:36.541565Z","steps":["trace[294132644] 'process raft request' (duration: 149.69724ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:25:06.757715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14386} {"level":"info","ts":"2026-08-28T22:25:06.841395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14386,"took":"80.527718ms","hash":2453546592,"current-db-size-bytes":44089344,"current-db-size":"44 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T22:25:06.841472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2453546592,"revision":14386,"compact-revision":12312} {"level":"info","ts":"2026-08-28T22:25:19.839368Z","caller":"traceutil/trace.go:171","msg":"trace[1422461028] transaction","detail":"{read_only:false; response_revision:16971; number_of_response:1; }","duration":"237.363509ms","start":"2026-08-28T22:25:19.601975Z","end":"2026-08-28T22:25:19.839339Z","steps":["trace[1422461028] 'process raft request' (duration: 237.147573ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:25:20.040923Z","caller":"traceutil/trace.go:171","msg":"trace[2046698929] transaction","detail":"{read_only:false; response_revision:16972; number_of_response:1; }","duration":"178.926213ms","start":"2026-08-28T22:25:19.861957Z","end":"2026-08-28T22:25:20.040883Z","steps":["trace[2046698929] 'process raft request' (duration: 146.146765ms)","trace[2046698929] 'compare' (duration: 32.469539ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:25:20.041174Z","caller":"traceutil/trace.go:171","msg":"trace[798044659] linearizableReadLoop","detail":"{readStateIndex:17561; appliedIndex:17560; }","duration":"156.930294ms","start":"2026-08-28T22:25:19.884213Z","end":"2026-08-28T22:25:20.041143Z","steps":["trace[798044659] 'read index received' (duration: 123.908279ms)","trace[798044659] 'applied index is now lower than readState.Index' (duration: 33.020455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:25:20.041532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.702182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:25:20.041588Z","caller":"traceutil/trace.go:171","msg":"trace[476350341] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:16973; }","duration":"147.801334ms","start":"2026-08-28T22:25:19.89377Z","end":"2026-08-28T22:25:20.041571Z","steps":["trace[476350341] 'agreement among raft nodes before linearized reading' (duration: 147.690221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:25:20.041631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.377417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfhh6\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-08-28T22:25:20.041747Z","caller":"traceutil/trace.go:171","msg":"trace[593487289] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfhh6; range_end:; response_count:1; response_revision:16973; }","duration":"157.553653ms","start":"2026-08-28T22:25:19.884177Z","end":"2026-08-28T22:25:20.04173Z","steps":["trace[593487289] 'agreement among raft nodes before linearized reading' (duration: 157.258564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:25:20.041762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.863578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfhh6\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-08-28T22:25:20.04186Z","caller":"traceutil/trace.go:171","msg":"trace[1139164979] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfhh6; range_end:; response_count:1; response_revision:16973; }","duration":"120.992763ms","start":"2026-08-28T22:25:19.920842Z","end":"2026-08-28T22:25:20.041835Z","steps":["trace[1139164979] 'agreement among raft nodes before linearized reading' (duration: 120.691564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:25:20.041933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.580205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-28T22:25:20.042054Z","caller":"traceutil/trace.go:171","msg":"trace[1191042265] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:16973; }","duration":"131.795631ms","start":"2026-08-28T22:25:19.910232Z","end":"2026-08-28T22:25:20.042028Z","steps":["trace[1191042265] 'agreement among raft nodes before linearized reading' (duration: 131.39656ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:25:20.188941Z","caller":"traceutil/trace.go:171","msg":"trace[292512328] linearizableReadLoop","detail":"{readStateIndex:17563; appliedIndex:17562; }","duration":"124.088725ms","start":"2026-08-28T22:25:20.064827Z","end":"2026-08-28T22:25:20.188916Z","steps":["trace[292512328] 'read index received' (duration: 115.758679ms)","trace[292512328] 'applied index is now lower than readState.Index' (duration: 8.328476ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:25:20.189178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.329551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T22:25:20.189218Z","caller":"traceutil/trace.go:171","msg":"trace[868406794] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:16973; }","duration":"124.441884ms","start":"2026-08-28T22:25:20.064764Z","end":"2026-08-28T22:25:20.189206Z","steps":["trace[868406794] 'agreement among raft nodes before linearized reading' (duration: 124.234398ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:30:06.768868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16904} {"level":"info","ts":"2026-08-28T22:30:06.882044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16904,"took":"108.373643ms","hash":864404976,"current-db-size-bytes":46198784,"current-db-size":"46 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-28T22:30:06.882131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":864404976,"revision":16904,"compact-revision":14386} {"level":"info","ts":"2026-08-28T22:30:47.885219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T22:30:47.893171Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-28T22:30:47.893355Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-28T22:30:51.3628Z","caller":"traceutil/trace.go:171","msg":"trace[1536239125] transaction","detail":"{read_only:false; response_revision:19354; number_of_response:1; }","duration":"164.543203ms","start":"2026-08-28T22:30:51.19823Z","end":"2026-08-28T22:30:51.362773Z","steps":["trace[1536239125] 'process raft request' (duration: 164.349987ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:30:58.548991Z","caller":"traceutil/trace.go:171","msg":"trace[2050374242] transaction","detail":"{read_only:false; response_revision:19394; number_of_response:1; }","duration":"172.809068ms","start":"2026-08-28T22:30:58.376149Z","end":"2026-08-28T22:30:58.548958Z","steps":["trace[2050374242] 'process raft request' (duration: 172.616612ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:30:58.691634Z","caller":"traceutil/trace.go:171","msg":"trace[583988957] linearizableReadLoop","detail":"{readStateIndex:20067; appliedIndex:20064; }","duration":"268.481695ms","start":"2026-08-28T22:30:58.423106Z","end":"2026-08-28T22:30:58.691588Z","steps":["trace[583988957] 'read index received' (duration: 125.655044ms)","trace[583988957] 'applied index is now lower than readState.Index' (duration: 142.824991ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:30:58.691962Z","caller":"traceutil/trace.go:171","msg":"trace[424962502] transaction","detail":"{read_only:false; response_revision:19395; number_of_response:1; }","duration":"291.798454ms","start":"2026-08-28T22:30:58.400132Z","end":"2026-08-28T22:30:58.69193Z","steps":["trace[424962502] 'process raft request' (duration: 259.102978ms)","trace[424962502] 'compare' (duration: 31.82734ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:30:58.692127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.997689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-28T22:30:58.692224Z","caller":"traceutil/trace.go:171","msg":"trace[1163938826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19396; }","duration":"269.135364ms","start":"2026-08-28T22:30:58.423065Z","end":"2026-08-28T22:30:58.6922Z","steps":["trace[1163938826] 'agreement among raft nodes before linearized reading' (duration: 268.851785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:30:58.692214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.140097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T22:30:58.692273Z","caller":"traceutil/trace.go:171","msg":"trace[1215768992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19396; }","duration":"264.231139ms","start":"2026-08-28T22:30:58.42803Z","end":"2026-08-28T22:30:58.692261Z","steps":["trace[1215768992] 'agreement among raft nodes before linearized reading' (duration: 264.047073ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:30:58.692568Z","caller":"traceutil/trace.go:171","msg":"trace[1453672523] transaction","detail":"{read_only:false; response_revision:19396; number_of_response:1; }","duration":"290.851086ms","start":"2026-08-28T22:30:58.401696Z","end":"2026-08-28T22:30:58.692547Z","steps":["trace[1453672523] 'process raft request' (duration: 289.829086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:30:59.557782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.855066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfhh6\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-08-28T22:30:59.55787Z","caller":"traceutil/trace.go:171","msg":"trace[986009080] transaction","detail":"{read_only:false; response_revision:19401; number_of_response:1; }","duration":"161.272196ms","start":"2026-08-28T22:30:59.39656Z","end":"2026-08-28T22:30:59.557832Z","steps":["trace[986009080] 'process raft request' (duration: 161.07185ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:30:59.557905Z","caller":"traceutil/trace.go:171","msg":"trace[523137864] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfhh6; range_end:; response_count:1; response_revision:19400; }","duration":"269.052442ms","start":"2026-08-28T22:30:59.288825Z","end":"2026-08-28T22:30:59.557878Z","steps":["trace[523137864] 'range keys from in-memory index tree' (duration: 268.594838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:30:59.850042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.196049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:30:59.850138Z","caller":"traceutil/trace.go:171","msg":"trace[1667519686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19401; }","duration":"104.362204ms","start":"2026-08-28T22:30:59.745752Z","end":"2026-08-28T22:30:59.850114Z","steps":["trace[1667519686] 'range keys from in-memory index tree' (duration: 103.978883ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:35:06.776457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19054} {"level":"info","ts":"2026-08-28T22:35:06.877206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19054,"took":"96.275128ms","hash":3065060763,"current-db-size-bytes":47058944,"current-db-size":"47 MB","current-db-size-in-use-bytes":34222080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-28T22:35:06.877294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065060763,"revision":19054,"compact-revision":16904} {"level":"info","ts":"2026-08-28T22:37:58.726409Z","caller":"traceutil/trace.go:171","msg":"trace[904217988] linearizableReadLoop","detail":"{readStateIndex:23342; appliedIndex:23341; }","duration":"313.626397ms","start":"2026-08-28T22:37:58.412726Z","end":"2026-08-28T22:37:58.726352Z","steps":["trace[904217988] 'read index received' (duration: 313.415251ms)","trace[904217988] 'applied index is now lower than readState.Index' (duration: 209.426µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:37:58.726529Z","caller":"traceutil/trace.go:171","msg":"trace[1569783501] transaction","detail":"{read_only:false; response_revision:22565; number_of_response:1; }","duration":"323.696033ms","start":"2026-08-28T22:37:58.40277Z","end":"2026-08-28T22:37:58.726466Z","steps":["trace[1569783501] 'process raft request' (duration: 323.362023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:58.726699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.945546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-28T22:37:58.726719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:37:58.402749Z","time spent":"323.865899ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:37:58.726753Z","caller":"traceutil/trace.go:171","msg":"trace[1296859267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22565; }","duration":"314.052309ms","start":"2026-08-28T22:37:58.412686Z","end":"2026-08-28T22:37:58.726738Z","steps":["trace[1296859267] 'agreement among raft nodes before linearized reading' (duration: 313.837763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:58.726807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:37:58.412667Z","time spent":"314.128931ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-28T22:37:59.014997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.996721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T22:37:59.015186Z","caller":"traceutil/trace.go:171","msg":"trace[1523968689] transaction","detail":"{read_only:false; response_revision:22566; number_of_response:1; }","duration":"564.612957ms","start":"2026-08-28T22:37:58.450545Z","end":"2026-08-28T22:37:59.015157Z","steps":["trace[1523968689] 'process raft request' (duration: 413.341857ms)","trace[1523968689] 'compare' (duration: 150.830247ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:37:59.0153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:37:58.450516Z","time spent":"564.75041ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:37:59.015568Z","caller":"traceutil/trace.go:171","msg":"trace[503339610] transaction","detail":"{read_only:false; response_revision:22567; number_of_response:1; }","duration":"560.525066ms","start":"2026-08-28T22:37:58.455018Z","end":"2026-08-28T22:37:59.015543Z","steps":["trace[503339610] 'process raft request' (duration: 560.081363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.015744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:37:58.455012Z","time spent":"560.640209ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T22:37:59.015835Z","caller":"traceutil/trace.go:171","msg":"trace[2040593794] transaction","detail":"{read_only:false; response_revision:22568; number_of_response:1; }","duration":"284.23682ms","start":"2026-08-28T22:37:58.731523Z","end":"2026-08-28T22:37:59.01576Z","steps":["trace[2040593794] 'process raft request' (duration: 283.911201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.336463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.564222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-28T22:37:59.336616Z","caller":"traceutil/trace.go:171","msg":"trace[1157231283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22569; }","duration":"143.83001ms","start":"2026-08-28T22:37:59.192753Z","end":"2026-08-28T22:37:59.336583Z","steps":["trace[1157231283] 'range keys from in-memory index tree' (duration: 143.254603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.336837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.831919ms","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-28T22:37:59.336948Z","caller":"traceutil/trace.go:171","msg":"trace[1039897011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22569; }","duration":"146.008434ms","start":"2026-08-28T22:37:59.190915Z","end":"2026-08-28T22:37:59.336923Z","steps":["trace[1039897011] 'range keys from in-memory index tree' (duration: 144.998604ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:37:59.570812Z","caller":"traceutil/trace.go:171","msg":"trace[1496388412] transaction","detail":"{read_only:false; response_revision:22570; number_of_response:1; }","duration":"270.31218ms","start":"2026-08-28T22:37:59.30047Z","end":"2026-08-28T22:37:59.570782Z","steps":["trace[1496388412] 'process raft request' (duration: 270.111784ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:37:59.609502Z","caller":"traceutil/trace.go:171","msg":"trace[1344482537] transaction","detail":"{read_only:false; response_revision:22572; number_of_response:1; }","duration":"267.376283ms","start":"2026-08-28T22:37:59.342095Z","end":"2026-08-28T22:37:59.609471Z","steps":["trace[1344482537] 'process raft request' (duration: 267.326151ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:37:59.609611Z","caller":"traceutil/trace.go:171","msg":"trace[1854458758] transaction","detail":"{read_only:false; response_revision:22571; number_of_response:1; }","duration":"267.549319ms","start":"2026-08-28T22:37:59.342008Z","end":"2026-08-28T22:37:59.609558Z","steps":["trace[1854458758] 'process raft request' (duration: 267.217119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.956246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.65439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T22:37:59.956397Z","caller":"traceutil/trace.go:171","msg":"trace[867921701] linearizableReadLoop","detail":"{readStateIndex:23350; appliedIndex:23349; }","duration":"262.840889ms","start":"2026-08-28T22:37:59.693514Z","end":"2026-08-28T22:37:59.956355Z","steps":["trace[867921701] 'read index received' (duration: 108.305493ms)","trace[867921701] 'applied index is now lower than readState.Index' (duration: 154.533236ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:37:59.956497Z","caller":"traceutil/trace.go:171","msg":"trace[636015942] transaction","detail":"{read_only:false; response_revision:22573; number_of_response:1; }","duration":"305.189608ms","start":"2026-08-28T22:37:59.651267Z","end":"2026-08-28T22:37:59.956456Z","steps":["trace[636015942] 'process raft request' (duration: 150.698573ms)","trace[636015942] 'compare' (duration: 153.221758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T22:37:59.956558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.046046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T22:37:59.956594Z","caller":"traceutil/trace.go:171","msg":"trace[1361124471] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:22573; }","duration":"263.128418ms","start":"2026-08-28T22:37:59.693452Z","end":"2026-08-28T22:37:59.956581Z","steps":["trace[1361124471] 'agreement among raft nodes before linearized reading' (duration: 263.044845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.956702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T22:37:59.651235Z","time spent":"305.351883ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T22:37:59.956907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.894338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:37:59.956945Z","caller":"traceutil/trace.go:171","msg":"trace[1459901525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22573; }","duration":"210.939699ms","start":"2026-08-28T22:37:59.745996Z","end":"2026-08-28T22:37:59.956936Z","steps":["trace[1459901525] 'agreement among raft nodes before linearized reading' (duration: 210.870127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.957102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.991987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:37:59.957204Z","caller":"traceutil/trace.go:171","msg":"trace[1808482670] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:22573; }","duration":"180.127121ms","start":"2026-08-28T22:37:59.777054Z","end":"2026-08-28T22:37:59.957181Z","steps":["trace[1808482670] 'agreement among raft nodes before linearized reading' (duration: 179.915885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:37:59.957198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.649653ms","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-28T22:37:59.957263Z","caller":"traceutil/trace.go:171","msg":"trace[1125954222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22573; }","duration":"186.736055ms","start":"2026-08-28T22:37:59.770509Z","end":"2026-08-28T22:37:59.957245Z","steps":["trace[1125954222] 'agreement among raft nodes before linearized reading' (duration: 186.469487ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:38:00.175102Z","caller":"traceutil/trace.go:171","msg":"trace[73324283] transaction","detail":"{read_only:false; response_revision:22574; number_of_response:1; }","duration":"212.527656ms","start":"2026-08-28T22:37:59.962516Z","end":"2026-08-28T22:38:00.175044Z","steps":["trace[73324283] 'process raft request' (duration: 164.633923ms)","trace[73324283] 'compare' (duration: 47.517121ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:40:06.785178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21387} {"level":"info","ts":"2026-08-28T22:40:06.906001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21387,"took":"116.343491ms","hash":1674625884,"current-db-size-bytes":47910912,"current-db-size":"48 MB","current-db-size-in-use-bytes":29683712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-28T22:40:06.906075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1674625884,"revision":21387,"compact-revision":19054} {"level":"info","ts":"2026-08-28T22:42:26.888803Z","caller":"traceutil/trace.go:171","msg":"trace[1131179071] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"238.164001ms","start":"2026-08-28T22:42:26.650602Z","end":"2026-08-28T22:42:26.888766Z","steps":["trace[1131179071] 'process raft request' (duration: 210.508876ms)","trace[1131179071] 'compare' (duration: 27.271294ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:42:26.946083Z","caller":"traceutil/trace.go:171","msg":"trace[1513352843] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26045; }","duration":"199.165482ms","start":"2026-08-28T22:42:26.746885Z","end":"2026-08-28T22:42:26.946051Z","steps":["trace[1513352843] 'read index received' (duration: 114.27868ms)","trace[1513352843] 'applied index is now lower than readState.Index' (duration: 84.885712ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T22:42:26.94612Z","caller":"traceutil/trace.go:171","msg":"trace[2116368252] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"242.695945ms","start":"2026-08-28T22:42:26.703381Z","end":"2026-08-28T22:42:26.946076Z","steps":["trace[2116368252] 'process raft request' (duration: 242.496139ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:42:26.94614Z","caller":"traceutil/trace.go:171","msg":"trace[499928076] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"162.841051ms","start":"2026-08-28T22:42:26.783267Z","end":"2026-08-28T22:42:26.946108Z","steps":["trace[499928076] 'process raft request' (duration: 162.751438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:42:26.946236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.340277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:42:26.946263Z","caller":"traceutil/trace.go:171","msg":"trace[952059071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25191; }","duration":"199.390278ms","start":"2026-08-28T22:42:26.746864Z","end":"2026-08-28T22:42:26.946254Z","steps":["trace[952059071] 'agreement among raft nodes before linearized reading' (duration: 199.317266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T22:42:26.94649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.038339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T22:42:26.94656Z","caller":"traceutil/trace.go:171","msg":"trace[1530718912] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25191; }","duration":"197.231395ms","start":"2026-08-28T22:42:26.74931Z","end":"2026-08-28T22:42:26.946542Z","steps":["trace[1530718912] 'agreement among raft nodes before linearized reading' (duration: 196.974567ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T22:44:33.565321Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aa4.wal"} {"level":"info","ts":"2026-08-28T22:45:06.794492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23599} {"level":"info","ts":"2026-08-28T22:45:06.926192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23599,"took":"126.2665ms","hash":4165061308,"current-db-size-bytes":52211712,"current-db-size":"52 MB","current-db-size-in-use-bytes":43831296,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-28T22:45:06.926292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4165061308,"revision":23599,"compact-revision":21387} {"level":"info","ts":"2026-08-28T22:50:06.803516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26683} {"level":"info","ts":"2026-08-28T22:50:06.93874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26683,"took":"130.720411ms","hash":3230048904,"current-db-size-bytes":52211712,"current-db-size":"52 MB","current-db-size-in-use-bytes":30601216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T22:50:06.9388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3230048904,"revision":26683,"compact-revision":23599} {"level":"info","ts":"2026-08-28T22:50:22.174494Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T22:50:22.181881Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-08-28T22:50:22.181999Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004}