{"level":"warn","ts":"2026-03-19T21:51:48.414673Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:51:48.414776Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-03-19T21:51:48.414847Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:51:48.414858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-19T21:51:48.414878Z","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-03-19T21:51:48.415647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-03-19T21:51:48.415746Z","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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-03-19T21:51:48.419573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.529725ms"} {"level":"info","ts":"2026-03-19T21:51:48.42549Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-03-19T21:51:48.425582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T21:51:48.425602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-03-19T21:51:48.425617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T21:51:48.42563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-03-19T21:51:48.425678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-03-19T21:51:48.431752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T21:51:48.433268Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T21:51:48.436118Z","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-03-19T21:51:48.43921Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T21:51:48.43945Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T21:51:48.439547Z","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-03-19T21:51:48.439729Z","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-03-19T21:51:48.439752Z","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-03-19T21:51:48.44176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-03-19T21:51:48.447075Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-19T21:51:48.449015Z","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-03-19T21:51:48.449286Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T21:51:48.449318Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T21:51:48.449573Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-19T21:51:48.44967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-19T21:51:49.327133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T21:51:49.327279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T21:51:49.327316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-03-19T21:51:49.327343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-03-19T21:51:49.327356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-19T21:51:49.327376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-03-19T21:51:49.327396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-19T21:51:49.328348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:51:49.32918Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T21:51:49.329179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:51:49.329262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:51:49.329775Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T21:51:49.329887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T21:51:49.329917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:51:49.330156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:51:49.330233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:51:49.331611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T21:51:49.333025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"warn","ts":"2026-03-19T21:52:27.323673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.800132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z5vmn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-19T21:52:27.323849Z","caller":"traceutil/trace.go:171","msg":"trace[540404642] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z5vmn; range_end:; response_count:1; response_revision:560; }","duration":"149.033517ms","start":"2026-03-19T21:52:27.174786Z","end":"2026-03-19T21:52:27.32382Z","steps":["trace[540404642] 'range keys from in-memory index tree' (duration: 148.657798ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:46.724816Z","caller":"traceutil/trace.go:171","msg":"trace[1190382932] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"270.314458ms","start":"2026-03-19T21:52:46.454452Z","end":"2026-03-19T21:52:46.724766Z","steps":["trace[1190382932] 'process raft request' (duration: 270.027091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:53:36.026736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.06701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T21:53:36.02679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.930086ms","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-03-19T21:53:36.026839Z","caller":"traceutil/trace.go:171","msg":"trace[1484366617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1589; }","duration":"155.227564ms","start":"2026-03-19T21:53:35.871586Z","end":"2026-03-19T21:53:36.026813Z","steps":["trace[1484366617] 'range keys from in-memory index tree' (duration: 154.856374ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:36.026869Z","caller":"traceutil/trace.go:171","msg":"trace[344656247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1589; }","duration":"171.073359ms","start":"2026-03-19T21:53:35.855778Z","end":"2026-03-19T21:53:36.026851Z","steps":["trace[344656247] 'range keys from in-memory index tree' (duration: 170.757061ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:36.160041Z","caller":"traceutil/trace.go:171","msg":"trace[1195288174] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"128.442782ms","start":"2026-03-19T21:53:36.031577Z","end":"2026-03-19T21:53:36.16002Z","steps":["trace[1195288174] 'process raft request' (duration: 128.280658ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:36.178171Z","caller":"traceutil/trace.go:171","msg":"trace[86277342] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"146.119062ms","start":"2026-03-19T21:53:36.032032Z","end":"2026-03-19T21:53:36.178151Z","steps":["trace[86277342] 'process raft request' (duration: 145.762464ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:36.667727Z","caller":"traceutil/trace.go:171","msg":"trace[1820714680] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"201.1957ms","start":"2026-03-19T21:53:36.466503Z","end":"2026-03-19T21:53:36.667699Z","steps":["trace[1820714680] 'process raft request' (duration: 201.010496ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:53:36.670068Z","caller":"traceutil/trace.go:171","msg":"trace[1084044862] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"199.146111ms","start":"2026-03-19T21:53:36.470881Z","end":"2026-03-19T21:53:36.670027Z","steps":["trace[1084044862] 'process raft request' (duration: 198.882464ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:21.849534Z","caller":"traceutil/trace.go:171","msg":"trace[751061313] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"132.549041ms","start":"2026-03-19T21:54:21.716947Z","end":"2026-03-19T21:54:21.849496Z","steps":["trace[751061313] 'read index received' (duration: 108.031885ms)","trace[751061313] 'applied index is now lower than readState.Index' (duration: 24.515726ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:21.849584Z","caller":"traceutil/trace.go:171","msg":"trace[3754708] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"136.432956ms","start":"2026-03-19T21:54:21.713083Z","end":"2026-03-19T21:54:21.849516Z","steps":["trace[3754708] 'process raft request' (duration: 111.91849ms)","trace[3754708] 'compare' (duration: 24.314961ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:54:21.849746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.764596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T21:54:21.849843Z","caller":"traceutil/trace.go:171","msg":"trace[588126519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1870; }","duration":"132.954731ms","start":"2026-03-19T21:54:21.716869Z","end":"2026-03-19T21:54:21.849824Z","steps":["trace[588126519] 'agreement among raft nodes before linearized reading' (duration: 132.722416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:21.849851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.764967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-19T21:54:21.849896Z","caller":"traceutil/trace.go:171","msg":"trace[344819723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"132.88448ms","start":"2026-03-19T21:54:21.716999Z","end":"2026-03-19T21:54:21.849884Z","steps":["trace[344819723] 'agreement among raft nodes before linearized reading' (duration: 132.700545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.167682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.804793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:54:28.167815Z","caller":"traceutil/trace.go:171","msg":"trace[1803250249] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"290.846695ms","start":"2026-03-19T21:54:27.876947Z","end":"2026-03-19T21:54:28.167793Z","steps":["trace[1803250249] 'read index received' (duration: 67.827512ms)","trace[1803250249] 'applied index is now lower than readState.Index' (duration: 223.017793ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:28.167905Z","caller":"traceutil/trace.go:171","msg":"trace[336339850] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"357.045938ms","start":"2026-03-19T21:54:27.810845Z","end":"2026-03-19T21:54:28.167891Z","steps":["trace[336339850] 'process raft request' (duration: 133.849841ms)","trace[336339850] 'compare' (duration: 221.621909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:54:28.168006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:54:27.810821Z","time spent":"357.15048ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:54:28.16819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.234904ms","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-03-19T21:54:28.168283Z","caller":"traceutil/trace.go:171","msg":"trace[50758058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1900; }","duration":"291.376637ms","start":"2026-03-19T21:54:27.876887Z","end":"2026-03-19T21:54:28.168264Z","steps":["trace[50758058] 'agreement among raft nodes before linearized reading' (duration: 291.134551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.168205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.649122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:54:28.168403Z","caller":"traceutil/trace.go:171","msg":"trace[399311158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"224.868608ms","start":"2026-03-19T21:54:27.943508Z","end":"2026-03-19T21:54:28.168376Z","steps":["trace[399311158] 'agreement among raft nodes before linearized reading' (duration: 224.614132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.168361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.798009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T21:54:28.168557Z","caller":"traceutil/trace.go:171","msg":"trace[156977654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"239.126787ms","start":"2026-03-19T21:54:27.929406Z","end":"2026-03-19T21:54:28.168533Z","steps":["trace[156977654] 'agreement among raft nodes before linearized reading' (duration: 238.620765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.168429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.161318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-19T21:54:28.168725Z","caller":"traceutil/trace.go:171","msg":"trace[1183825487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1900; }","duration":"198.495447ms","start":"2026-03-19T21:54:27.970182Z","end":"2026-03-19T21:54:28.168677Z","steps":["trace[1183825487] 'agreement among raft nodes before linearized reading' (duration: 197.913743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.907477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:54:28.527258Z","caller":"traceutil/trace.go:171","msg":"trace[1010601498] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1969; }","duration":"351.422042ms","start":"2026-03-19T21:54:28.175818Z","end":"2026-03-19T21:54:28.52724Z","steps":["trace[1010601498] 'read index received' (duration: 15.953832ms)","trace[1010601498] 'applied index is now lower than readState.Index' (duration: 335.46642ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:28.52726Z","caller":"traceutil/trace.go:171","msg":"trace[1305001872] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"354.030685ms","start":"2026-03-19T21:54:28.173193Z","end":"2026-03-19T21:54:28.527223Z","steps":["trace[1305001872] 'process raft request' (duration: 187.560215ms)","trace[1305001872] 'compare' (duration: 165.696822ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:28.527405Z","caller":"traceutil/trace.go:171","msg":"trace[1153994809] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"353.991413ms","start":"2026-03-19T21:54:28.173368Z","end":"2026-03-19T21:54:28.527359Z","steps":["trace[1153994809] 'process raft request' (duration: 353.770808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.527622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.244008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:54:28.527628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:54:28.173343Z","time spent":"354.182437ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:54:28.527694Z","caller":"traceutil/trace.go:171","msg":"trace[1249952365] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1903; }","duration":"300.347712ms","start":"2026-03-19T21:54:28.227326Z","end":"2026-03-19T21:54:28.527674Z","steps":["trace[1249952365] 'agreement among raft nodes before linearized reading' (duration: 300.227488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.527618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:54:28.173156Z","time spent":"354.277781ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:54:28.527754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:54:28.227302Z","time spent":"300.436583ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:54:28.52824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.404956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T21:54:28.528327Z","caller":"traceutil/trace.go:171","msg":"trace[204648063] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1903; }","duration":"352.51624ms","start":"2026-03-19T21:54:28.175792Z","end":"2026-03-19T21:54:28.528308Z","steps":["trace[204648063] 'agreement among raft nodes before linearized reading' (duration: 351.578146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:28.528384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:54:28.175783Z","time spent":"352.586611ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":265,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-19T21:58:31.905272Z","caller":"traceutil/trace.go:171","msg":"trace[156579181] transaction","detail":"{read_only:false; response_revision:3467; number_of_response:1; }","duration":"187.335907ms","start":"2026-03-19T21:58:31.717908Z","end":"2026-03-19T21:58:31.905244Z","steps":["trace[156579181] 'process raft request' (duration: 187.166523ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:58:32.027615Z","caller":"traceutil/trace.go:171","msg":"trace[871652473] linearizableReadLoop","detail":"{readStateIndex:3602; appliedIndex:3598; }","duration":"170.098392ms","start":"2026-03-19T21:58:31.857487Z","end":"2026-03-19T21:58:32.027586Z","steps":["trace[871652473] 'read index received' (duration: 47.585155ms)","trace[871652473] 'applied index is now lower than readState.Index' (duration: 122.512247ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:58:32.027622Z","caller":"traceutil/trace.go:171","msg":"trace[578337186] transaction","detail":"{read_only:false; response_revision:3468; number_of_response:1; }","duration":"248.729204ms","start":"2026-03-19T21:58:31.778852Z","end":"2026-03-19T21:58:32.027581Z","steps":["trace[578337186] 'process raft request' (duration: 248.486758ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:58:32.027724Z","caller":"traceutil/trace.go:171","msg":"trace[1910655519] transaction","detail":"{read_only:false; response_revision:3469; number_of_response:1; }","duration":"231.465787ms","start":"2026-03-19T21:58:31.796229Z","end":"2026-03-19T21:58:32.027695Z","steps":["trace[1910655519] 'process raft request' (duration: 231.271413ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:58:32.027763Z","caller":"traceutil/trace.go:171","msg":"trace[1895829518] transaction","detail":"{read_only:false; response_revision:3470; number_of_response:1; }","duration":"219.517033ms","start":"2026-03-19T21:58:31.80823Z","end":"2026-03-19T21:58:32.027747Z","steps":["trace[1895829518] 'process raft request' (duration: 219.319957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:58:32.028065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.098263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T21:58:32.028059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.575114ms","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-03-19T21:58:32.028112Z","caller":"traceutil/trace.go:171","msg":"trace[1558288053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3470; }","duration":"121.191386ms","start":"2026-03-19T21:58:31.906908Z","end":"2026-03-19T21:58:32.028099Z","steps":["trace[1558288053] 'agreement among raft nodes before linearized reading' (duration: 121.097754ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:58:32.028121Z","caller":"traceutil/trace.go:171","msg":"trace[1455181784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3470; }","duration":"170.689046ms","start":"2026-03-19T21:58:31.857419Z","end":"2026-03-19T21:58:32.028108Z","steps":["trace[1455181784] 'agreement among raft nodes before linearized reading' (duration: 170.451141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:58:32.028232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.206848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T21:58:32.028274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.368739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T21:58:32.028413Z","caller":"traceutil/trace.go:171","msg":"trace[2090271227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3470; }","duration":"170.574554ms","start":"2026-03-19T21:58:31.857812Z","end":"2026-03-19T21:58:32.028387Z","steps":["trace[2090271227] 'agreement among raft nodes before linearized reading' (duration: 170.171314ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:58:32.028331Z","caller":"traceutil/trace.go:171","msg":"trace[1103420952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3470; }","duration":"166.335162ms","start":"2026-03-19T21:58:31.861968Z","end":"2026-03-19T21:58:32.028303Z","steps":["trace[1103420952] 'agreement among raft nodes before linearized reading' (duration: 166.056485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:58:32.028416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.167194ms","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-03-19T21:58:32.028556Z","caller":"traceutil/trace.go:171","msg":"trace[1762883633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3470; }","duration":"155.373378ms","start":"2026-03-19T21:58:31.873149Z","end":"2026-03-19T21:58:32.028522Z","steps":["trace[1762883633] 'agreement among raft nodes before linearized reading' (duration: 154.843165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:00:02.478724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.239988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T22:00:02.478872Z","caller":"traceutil/trace.go:171","msg":"trace[1913699352] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4640; }","duration":"275.438882ms","start":"2026-03-19T22:00:02.203395Z","end":"2026-03-19T22:00:02.478834Z","steps":["trace[1913699352] 'count revisions from in-memory index tree' (duration: 275.112243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:00:02.479068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.29931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa715857-be90-4b2d-b0a0-832bddd068e9\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-19T22:00:02.479182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.681201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777453"} {"level":"info","ts":"2026-03-19T22:00:02.479187Z","caller":"traceutil/trace.go:171","msg":"trace[1537142521] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa715857-be90-4b2d-b0a0-832bddd068e9; range_end:; response_count:1; response_revision:4640; }","duration":"333.469844ms","start":"2026-03-19T22:00:02.145693Z","end":"2026-03-19T22:00:02.479162Z","steps":["trace[1537142521] 'range keys from in-memory index tree' (duration: 333.158406ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:00:02.479238Z","caller":"traceutil/trace.go:171","msg":"trace[610975613] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4640; }","duration":"324.757293ms","start":"2026-03-19T22:00:02.154465Z","end":"2026-03-19T22:00:02.479222Z","steps":["trace[610975613] 'range keys from in-memory index tree' (duration: 324.232401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:00:02.479242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:00:02.145673Z","time spent":"333.558405ms","remote":"127.0.0.1:53404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa715857-be90-4b2d-b0a0-832bddd068e9\" "} {"level":"warn","ts":"2026-03-19T22:00:02.479279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:00:02.154457Z","time spent":"324.809754ms","remote":"127.0.0.1:53352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":777477,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-03-19T22:00:02.479352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.53338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T22:00:02.479459Z","caller":"traceutil/trace.go:171","msg":"trace[268777537] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4640; }","duration":"298.671053ms","start":"2026-03-19T22:00:02.180761Z","end":"2026-03-19T22:00:02.479432Z","steps":["trace[268777537] 'count revisions from in-memory index tree' (duration: 298.438697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:00:02.848813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.040631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa715857-be90-4b2d-b0a0-832bddd068e9\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-19T22:00:02.848893Z","caller":"traceutil/trace.go:171","msg":"trace[32612707] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"132.850278ms","start":"2026-03-19T22:00:02.715991Z","end":"2026-03-19T22:00:02.848842Z","steps":["trace[32612707] 'process raft request' (duration: 126.441013ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:00:02.848924Z","caller":"traceutil/trace.go:171","msg":"trace[948820604] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa715857-be90-4b2d-b0a0-832bddd068e9; range_end:; response_count:1; response_revision:4647; }","duration":"137.203124ms","start":"2026-03-19T22:00:02.711694Z","end":"2026-03-19T22:00:02.848897Z","steps":["trace[948820604] 'range keys from in-memory index tree' (duration: 136.826405ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:00:45.572224Z","caller":"traceutil/trace.go:171","msg":"trace[39947885] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"252.085177ms","start":"2026-03-19T22:00:45.320106Z","end":"2026-03-19T22:00:45.572191Z","steps":["trace[39947885] 'process raft request' (duration: 251.78877ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:00:45.948326Z","caller":"traceutil/trace.go:171","msg":"trace[267430679] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"612.740058ms","start":"2026-03-19T22:00:45.335528Z","end":"2026-03-19T22:00:45.948268Z","steps":["trace[267430679] 'process raft request' (duration: 539.980841ms)","trace[267430679] 'compare' (duration: 72.561362ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T22:00:45.94841Z","caller":"traceutil/trace.go:171","msg":"trace[966413021] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5394; }","duration":"165.665751ms","start":"2026-03-19T22:00:45.782725Z","end":"2026-03-19T22:00:45.948391Z","steps":["trace[966413021] 'read index received' (duration: 92.74836ms)","trace[966413021] 'applied index is now lower than readState.Index' (duration: 72.916261ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T22:00:45.948541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.807235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T22:00:45.948569Z","caller":"traceutil/trace.go:171","msg":"trace[1155690890] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5203; }","duration":"165.906807ms","start":"2026-03-19T22:00:45.782657Z","end":"2026-03-19T22:00:45.948564Z","steps":["trace[1155690890] 'agreement among raft nodes before linearized reading' (duration: 165.836425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:00:45.948584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:00:45.335494Z","time spent":"612.954143ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T22:00:46.394491Z","caller":"traceutil/trace.go:171","msg":"trace[1656810309] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"122.486918ms","start":"2026-03-19T22:00:46.27197Z","end":"2026-03-19T22:00:46.394456Z","steps":["trace[1656810309] 'process raft request' (duration: 57.392536ms)","trace[1656810309] 'compare' (duration: 64.945609ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T22:01:49.358527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2792} {"level":"info","ts":"2026-03-19T22:01:49.41707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2792,"took":"56.654479ms","hash":1371176651,"current-db-size-bytes":40775680,"current-db-size":"41 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-19T22:01:49.417139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1371176651,"revision":2792,"compact-revision":-1} {"level":"info","ts":"2026-03-19T22:06:49.373475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5701} {"level":"info","ts":"2026-03-19T22:06:49.461111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5701,"took":"85.361771ms","hash":2541528332,"current-db-size-bytes":43823104,"current-db-size":"44 MB","current-db-size-in-use-bytes":23306240,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-19T22:06:49.461191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2541528332,"revision":5701,"compact-revision":2792} {"level":"warn","ts":"2026-03-19T22:06:53.229438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812626610688642,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T22:06:53.340294Z","caller":"traceutil/trace.go:171","msg":"trace[451974959] transaction","detail":"{read_only:false; response_revision:7783; number_of_response:1; }","duration":"615.102634ms","start":"2026-03-19T22:06:52.72517Z","end":"2026-03-19T22:06:53.340273Z","steps":["trace[451974959] 'process raft request' (duration: 614.93874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:06:53.340449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:52.725142Z","time spent":"615.223397ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T22:06:53.344768Z","caller":"traceutil/trace.go:171","msg":"trace[1303517575] transaction","detail":"{read_only:false; response_revision:7784; number_of_response:1; }","duration":"303.061574ms","start":"2026-03-19T22:06:53.04169Z","end":"2026-03-19T22:06:53.344751Z","steps":["trace[1303517575] 'process raft request' (duration: 302.86762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:06:53.344839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:53.041663Z","time spent":"303.140806ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T22:06:53.344832Z","caller":"traceutil/trace.go:171","msg":"trace[1812162189] transaction","detail":"{read_only:false; response_revision:7785; number_of_response:1; }","duration":"199.250458ms","start":"2026-03-19T22:06:53.145541Z","end":"2026-03-19T22:06:53.344791Z","steps":["trace[1812162189] 'process raft request' (duration: 199.162866ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:06:53.344755Z","caller":"traceutil/trace.go:171","msg":"trace[1177387687] linearizableReadLoop","detail":"{readStateIndex:8067; appliedIndex:8064; }","duration":"615.506954ms","start":"2026-03-19T22:06:52.72923Z","end":"2026-03-19T22:06:53.344737Z","steps":["trace[1177387687] 'read index received' (duration: 610.909223ms)","trace[1177387687] 'applied index is now lower than readState.Index' (duration: 4.597041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T22:06:53.34495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.709199ms","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-03-19T22:06:53.344986Z","caller":"traceutil/trace.go:171","msg":"trace[1097346770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7785; }","duration":"615.816601ms","start":"2026-03-19T22:06:52.729163Z","end":"2026-03-19T22:06:53.344979Z","steps":["trace[1097346770] 'agreement among raft nodes before linearized reading' (duration: 615.629787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:06:53.345016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:52.729139Z","time spent":"615.871943ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T22:06:53.345163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.812077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T22:06:53.345219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.585677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-19T22:06:53.345241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.952296ms","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-03-19T22:06:53.345252Z","caller":"traceutil/trace.go:171","msg":"trace[306231386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7785; }","duration":"598.679579ms","start":"2026-03-19T22:06:52.746563Z","end":"2026-03-19T22:06:53.345242Z","steps":["trace[306231386] 'agreement among raft nodes before linearized reading' (duration: 598.491624ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:06:53.34524Z","caller":"traceutil/trace.go:171","msg":"trace[93018900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7785; }","duration":"401.94814ms","start":"2026-03-19T22:06:52.943271Z","end":"2026-03-19T22:06:53.345219Z","steps":["trace[93018900] 'agreement among raft nodes before linearized reading' (duration: 401.789376ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:06:53.345272Z","caller":"traceutil/trace.go:171","msg":"trace[1697403813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7785; }","duration":"579.006087ms","start":"2026-03-19T22:06:52.766257Z","end":"2026-03-19T22:06:53.345263Z","steps":["trace[1697403813] 'agreement among raft nodes before linearized reading' (duration: 578.870164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:06:53.345292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:52.766246Z","time spent":"579.042078ms","remote":"127.0.0.1:53502","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-03-19T22:06:53.34529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:52.746534Z","time spent":"598.750272ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T22:06:53.345292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:06:52.943247Z","time spent":"402.037473ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T22:07:05.648543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.250849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T22:07:05.649054Z","caller":"traceutil/trace.go:171","msg":"trace[1912620404] transaction","detail":"{read_only:false; response_revision:7846; number_of_response:1; }","duration":"200.242592ms","start":"2026-03-19T22:07:05.448783Z","end":"2026-03-19T22:07:05.649025Z","steps":["trace[1912620404] 'process raft request' (duration: 67.42011ms)","trace[1912620404] 'compare' (duration: 132.023643ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T22:07:06.38465Z","caller":"traceutil/trace.go:171","msg":"trace[1487592848] transaction","detail":"{read_only:false; response_revision:7848; number_of_response:1; }","duration":"142.591877ms","start":"2026-03-19T22:07:06.24202Z","end":"2026-03-19T22:07:06.384612Z","steps":["trace[1487592848] 'process raft request' (duration: 100.162977ms)","trace[1487592848] 'compare' (duration: 42.264826ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T22:07:07.212966Z","caller":"traceutil/trace.go:171","msg":"trace[1702523898] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"103.812066ms","start":"2026-03-19T22:07:07.10909Z","end":"2026-03-19T22:07:07.212902Z","steps":["trace[1702523898] 'process raft request' (duration: 103.645041ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:07:34.126482Z","caller":"traceutil/trace.go:171","msg":"trace[1881082997] transaction","detail":"{read_only:false; response_revision:8040; number_of_response:1; }","duration":"313.953035ms","start":"2026-03-19T22:07:33.8125Z","end":"2026-03-19T22:07:34.126453Z","steps":["trace[1881082997] 'process raft request' (duration: 313.784061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T22:07:34.126707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T22:07:33.812486Z","time spent":"314.090558ms","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T22:07:34.36085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.652245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T22:07:34.361243Z","caller":"traceutil/trace.go:171","msg":"trace[2082326815] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"185.834936ms","start":"2026-03-19T22:07:34.17537Z","end":"2026-03-19T22:07:34.361205Z","steps":["trace[2082326815] 'process raft request' (duration: 70.717839ms)","trace[2082326815] 'compare' (duration: 114.287237ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T22:07:37.32923Z","caller":"traceutil/trace.go:171","msg":"trace[1758490372] transaction","detail":"{read_only:false; response_revision:8053; number_of_response:1; }","duration":"223.380369ms","start":"2026-03-19T22:07:37.105825Z","end":"2026-03-19T22:07:37.329205Z","steps":["trace[1758490372] 'process raft request' (duration: 223.193474ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:07:48.541601Z","caller":"traceutil/trace.go:171","msg":"trace[1623937913] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"111.55418ms","start":"2026-03-19T22:07:48.430019Z","end":"2026-03-19T22:07:48.541574Z","steps":["trace[1623937913] 'process raft request' (duration: 111.372756ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:07:48.631922Z","caller":"traceutil/trace.go:171","msg":"trace[815092648] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"189.953175ms","start":"2026-03-19T22:07:48.441944Z","end":"2026-03-19T22:07:48.631897Z","steps":["trace[815092648] 'process raft request' (duration: 189.785071ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T22:11:07.820608Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T22:11:07.825107Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-19T22:11:07.825197Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-19T22:11:49.379668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7766} {"level":"info","ts":"2026-03-19T22:11:49.451472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7766,"took":"61.10022ms","hash":2463507546,"current-db-size-bytes":43823104,"current-db-size":"44 MB","current-db-size-in-use-bytes":25194496,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-19T22:11:49.451564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2463507546,"revision":7766,"compact-revision":5701} {"level":"info","ts":"2026-03-19T22:16:49.397981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9846} {"level":"info","ts":"2026-03-19T22:16:49.475347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9846,"took":"74.259246ms","hash":1198334016,"current-db-size-bytes":43823104,"current-db-size":"44 MB","current-db-size-in-use-bytes":18079744,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-19T22:16:49.47543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1198334016,"revision":9846,"compact-revision":7766}