{"level":"warn","ts":"2026-05-30T16:41:56.66011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T16:41:56.660186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-05-30T16:41:56.660238Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T16:41:56.660245Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-30T16:41:56.66026Z","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-05-30T16:41:56.660853Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-30T16:41:56.660914Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-05-30T16:41:56.66565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.568867ms"} {"level":"info","ts":"2026-05-30T16:41:56.670645Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-30T16:41:56.670715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T16:41:56.670738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-30T16:41:56.670748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T16:41:56.670761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-30T16:41:56.670801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-30T16:41:56.678065Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T16:41:56.679239Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T16:41:56.680297Z","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-05-30T16:41:56.682397Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T16:41:56.682501Z","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-05-30T16:41:56.682584Z","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-05-30T16:41:56.682598Z","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-05-30T16:41:56.683285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T16:41:56.686363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-30T16:41:56.686559Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-30T16:41:56.690234Z","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-05-30T16:41:56.690424Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T16:41:56.690464Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T16:41:56.690566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-30T16:41:56.690582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-30T16:41:56.771199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T16:41:56.771256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T16:41:56.771281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-30T16:41:56.771298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-30T16:41:56.771312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-30T16:41:56.771325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-30T16:41:56.771346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-30T16:41:56.77195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T16:41:56.772039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T16:41:56.772127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T16:41:56.772211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T16:41:56.777159Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T16:41:56.772246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:41:56.774492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T16:41:56.777004Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-30T16:41:56.77796Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:41:56.778059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:41:56.778111Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-30T16:41:59.611247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.397841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-30T16:41:59.611421Z","caller":"traceutil/trace.go:171","msg":"trace[445743874] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"289.267433ms","start":"2026-05-30T16:41:59.322089Z","end":"2026-05-30T16:41:59.611357Z","steps":["trace[445743874] 'read index received' (duration: 145.046302ms)","trace[445743874] 'applied index is now lower than readState.Index' (duration: 144.219411ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:41:59.611612Z","caller":"traceutil/trace.go:171","msg":"trace[1622698120] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"292.494589ms","start":"2026-05-30T16:41:59.3191Z","end":"2026-05-30T16:41:59.611595Z","steps":["trace[1622698120] 'process raft request' (duration: 148.122027ms)","trace[1622698120] 'compare' (duration: 143.24692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:41:59.611907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.854974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-30T16:41:59.612044Z","caller":"traceutil/trace.go:171","msg":"trace[1390291806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"262.029956ms","start":"2026-05-30T16:41:59.349981Z","end":"2026-05-30T16:41:59.612011Z","steps":["trace[1390291806] 'agreement among raft nodes before linearized reading' (duration: 261.777763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:41:59.611937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.804369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-30T16:41:59.612175Z","caller":"traceutil/trace.go:171","msg":"trace[376207437] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"290.084642ms","start":"2026-05-30T16:41:59.322067Z","end":"2026-05-30T16:41:59.612151Z","steps":["trace[376207437] 'agreement among raft nodes before linearized reading' (duration: 289.671447ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:33.267981Z","caller":"traceutil/trace.go:171","msg":"trace[28107801] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"145.737075ms","start":"2026-05-30T16:42:33.122213Z","end":"2026-05-30T16:42:33.26795Z","steps":["trace[28107801] 'process raft request' (duration: 94.023797ms)","trace[28107801] 'compare' (duration: 51.488845ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:42:33.293869Z","caller":"traceutil/trace.go:171","msg":"trace[1031891298] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"107.818526ms","start":"2026-05-30T16:42:33.186003Z","end":"2026-05-30T16:42:33.293822Z","steps":["trace[1031891298] 'process raft request' (duration: 107.746175ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:33.293892Z","caller":"traceutil/trace.go:171","msg":"trace[1886693080] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"155.798006ms","start":"2026-05-30T16:42:33.138072Z","end":"2026-05-30T16:42:33.293869Z","steps":["trace[1886693080] 'process raft request' (duration: 155.561435ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:33.293902Z","caller":"traceutil/trace.go:171","msg":"trace[89490434] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"162.437443ms","start":"2026-05-30T16:42:33.131427Z","end":"2026-05-30T16:42:33.293864Z","steps":["trace[89490434] 'process raft request' (duration: 162.044581ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:38.869437Z","caller":"traceutil/trace.go:171","msg":"trace[2114043045] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"206.866509ms","start":"2026-05-30T16:42:38.662513Z","end":"2026-05-30T16:42:38.869379Z","steps":["trace[2114043045] 'process raft request' (duration: 206.685068ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:38.939957Z","caller":"traceutil/trace.go:171","msg":"trace[2061614894] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"262.814627ms","start":"2026-05-30T16:42:38.6771Z","end":"2026-05-30T16:42:38.939915Z","steps":["trace[2061614894] 'process raft request' (duration: 260.991425ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:39.577288Z","caller":"traceutil/trace.go:171","msg":"trace[381124710] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"134.772392ms","start":"2026-05-30T16:42:39.442491Z","end":"2026-05-30T16:42:39.577263Z","steps":["trace[381124710] 'process raft request' (duration: 134.192429ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:39.78754Z","caller":"traceutil/trace.go:171","msg":"trace[1171668332] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"160.253033ms","start":"2026-05-30T16:42:39.627258Z","end":"2026-05-30T16:42:39.787511Z","steps":["trace[1171668332] 'process raft request' (duration: 84.711467ms)","trace[1171668332] 'compare' (duration: 75.380475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:42:40.245559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.290497ms","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-05-30T16:42:40.24562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.197168ms","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-05-30T16:42:40.245664Z","caller":"traceutil/trace.go:171","msg":"trace[2049324866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"315.459428ms","start":"2026-05-30T16:42:39.930185Z","end":"2026-05-30T16:42:40.245645Z","steps":["trace[2049324866] 'range keys from in-memory index tree' (duration: 315.143466ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:42:40.245705Z","caller":"traceutil/trace.go:171","msg":"trace[2090177432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"239.337488ms","start":"2026-05-30T16:42:40.006345Z","end":"2026-05-30T16:42:40.245682Z","steps":["trace[2090177432] 'range keys from in-memory index tree' (duration: 238.988796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:42:40.245734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:42:39.93017Z","time spent":"315.553779ms","remote":"127.0.0.1:45982","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":"info","ts":"2026-05-30T16:42:44.771728Z","caller":"traceutil/trace.go:171","msg":"trace[1436139111] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"102.403327ms","start":"2026-05-30T16:42:44.669299Z","end":"2026-05-30T16:42:44.771702Z","steps":["trace[1436139111] 'process raft request' (duration: 99.318117ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:43:25.132859Z","caller":"traceutil/trace.go:171","msg":"trace[1745757466] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"154.888878ms","start":"2026-05-30T16:43:24.977944Z","end":"2026-05-30T16:43:25.132833Z","steps":["trace[1745757466] 'process raft request' (duration: 154.742243ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:00.754395Z","caller":"traceutil/trace.go:171","msg":"trace[831627371] linearizableReadLoop","detail":"{readStateIndex:1730; appliedIndex:1729; }","duration":"103.671338ms","start":"2026-05-30T16:44:00.650696Z","end":"2026-05-30T16:44:00.754367Z","steps":["trace[831627371] 'read index received' (duration: 65.69588ms)","trace[831627371] 'applied index is now lower than readState.Index' (duration: 37.974168ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:00.754522Z","caller":"traceutil/trace.go:171","msg":"trace[1401117286] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"104.839645ms","start":"2026-05-30T16:44:00.649669Z","end":"2026-05-30T16:44:00.754509Z","steps":["trace[1401117286] 'process raft request' (duration: 66.765175ms)","trace[1401117286] 'compare' (duration: 37.799773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:44:00.754687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.985105ms","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-05-30T16:44:00.75476Z","caller":"traceutil/trace.go:171","msg":"trace[733185146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1659; }","duration":"104.101059ms","start":"2026-05-30T16:44:00.65064Z","end":"2026-05-30T16:44:00.754741Z","steps":["trace[733185146] 'agreement among raft nodes before linearized reading' (duration: 103.882914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:00.760536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.828749ms","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-05-30T16:44:00.760611Z","caller":"traceutil/trace.go:171","msg":"trace[897317120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1660; }","duration":"102.955412ms","start":"2026-05-30T16:44:00.657641Z","end":"2026-05-30T16:44:00.760596Z","steps":["trace[897317120] 'agreement among raft nodes before linearized reading' (duration: 102.788458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:12.191646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.650631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:44:12.191839Z","caller":"traceutil/trace.go:171","msg":"trace[850756491] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"104.308493ms","start":"2026-05-30T16:44:12.087512Z","end":"2026-05-30T16:44:12.19182Z","steps":["trace[850756491] 'process raft request' (duration: 104.242442ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:12.191993Z","caller":"traceutil/trace.go:171","msg":"trace[811284221] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"175.398711ms","start":"2026-05-30T16:44:12.016547Z","end":"2026-05-30T16:44:12.191945Z","steps":["trace[811284221] 'process raft request' (duration: 71.192889ms)","trace[811284221] 'compare' (duration: 103.535779ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:13.55039Z","caller":"traceutil/trace.go:171","msg":"trace[842588145] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"157.490147ms","start":"2026-05-30T16:44:13.39287Z","end":"2026-05-30T16:44:13.55036Z","steps":["trace[842588145] 'read index received' (duration: 157.192101ms)","trace[842588145] 'applied index is now lower than readState.Index' (duration: 296.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:13.550486Z","caller":"traceutil/trace.go:171","msg":"trace[2122936294] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"163.833025ms","start":"2026-05-30T16:44:13.386581Z","end":"2026-05-30T16:44:13.550414Z","steps":["trace[2122936294] 'process raft request' (duration: 163.56422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:13.550626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.738622ms","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-05-30T16:44:13.550667Z","caller":"traceutil/trace.go:171","msg":"trace[104687191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1846; }","duration":"157.798673ms","start":"2026-05-30T16:44:13.392857Z","end":"2026-05-30T16:44:13.550655Z","steps":["trace[104687191] 'agreement among raft nodes before linearized reading' (duration: 157.610189ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:14.049472Z","caller":"traceutil/trace.go:171","msg":"trace[921755973] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"203.820653ms","start":"2026-05-30T16:44:13.845633Z","end":"2026-05-30T16:44:14.049454Z","steps":["trace[921755973] 'process raft request' (duration: 203.69801ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:43.071716Z","caller":"traceutil/trace.go:171","msg":"trace[335656819] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"113.754117ms","start":"2026-05-30T16:44:42.957928Z","end":"2026-05-30T16:44:43.071682Z","steps":["trace[335656819] 'process raft request' (duration: 73.594434ms)","trace[335656819] 'compare' (duration: 39.99592ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:43.22622Z","caller":"traceutil/trace.go:171","msg":"trace[1118956614] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"108.605931ms","start":"2026-05-30T16:44:43.117584Z","end":"2026-05-30T16:44:43.22619Z","steps":["trace[1118956614] 'process raft request' (duration: 60.36527ms)","trace[1118956614] 'compare' (duration: 48.081379ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:43.960501Z","caller":"traceutil/trace.go:171","msg":"trace[84626864] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"105.217848ms","start":"2026-05-30T16:44:43.855255Z","end":"2026-05-30T16:44:43.960473Z","steps":["trace[84626864] 'process raft request' (duration: 83.11641ms)","trace[84626864] 'compare' (duration: 22.002467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:44:44.535863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.845256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:44:44.536119Z","caller":"traceutil/trace.go:171","msg":"trace[1987784749] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"101.954634ms","start":"2026-05-30T16:44:44.434142Z","end":"2026-05-30T16:44:44.536097Z","steps":["trace[1987784749] 'process raft request' (duration: 101.848223ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:44.536305Z","caller":"traceutil/trace.go:171","msg":"trace[777571015] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2148; }","duration":"173.22576ms","start":"2026-05-30T16:44:44.363057Z","end":"2026-05-30T16:44:44.536283Z","steps":["trace[777571015] 'read index received' (duration: 50.816316ms)","trace[777571015] 'applied index is now lower than readState.Index' (duration: 122.407044ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:44:44.536415Z","caller":"traceutil/trace.go:171","msg":"trace[147167124] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"193.427456ms","start":"2026-05-30T16:44:44.342968Z","end":"2026-05-30T16:44:44.536395Z","steps":["trace[147167124] 'process raft request' (duration: 70.92937ms)","trace[147167124] 'compare' (duration: 121.619042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:44:44.53656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.487294ms","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-05-30T16:44:44.536642Z","caller":"traceutil/trace.go:171","msg":"trace[1860294629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2066; }","duration":"173.607075ms","start":"2026-05-30T16:44:44.363017Z","end":"2026-05-30T16:44:44.536624Z","steps":["trace[1860294629] 'agreement among raft nodes before linearized reading' (duration: 173.384882ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:44.632388Z","caller":"traceutil/trace.go:171","msg":"trace[2138467609] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"196.696368ms","start":"2026-05-30T16:44:44.435666Z","end":"2026-05-30T16:44:44.632363Z","steps":["trace[2138467609] 'process raft request' (duration: 196.518785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:44.63244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.483781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:44:44.632518Z","caller":"traceutil/trace.go:171","msg":"trace[800882549] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2067; }","duration":"153.614493ms","start":"2026-05-30T16:44:44.478886Z","end":"2026-05-30T16:44:44.632501Z","steps":["trace[800882549] 'agreement among raft nodes before linearized reading' (duration: 153.464761ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:44:44.724423Z","caller":"traceutil/trace.go:171","msg":"trace[91650432] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"174.009632ms","start":"2026-05-30T16:44:44.550388Z","end":"2026-05-30T16:44:44.724398Z","steps":["trace[91650432] 'process raft request' (duration: 173.8791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:44.724764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.793625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:44:44.724802Z","caller":"traceutil/trace.go:171","msg":"trace[1428807437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2068; }","duration":"167.867706ms","start":"2026-05-30T16:44:44.556921Z","end":"2026-05-30T16:44:44.724789Z","steps":["trace[1428807437] 'agreement among raft nodes before linearized reading' (duration: 167.805175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:44:44.725124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.076989ms","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-05-30T16:44:44.725169Z","caller":"traceutil/trace.go:171","msg":"trace[1915027386] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2068; }","duration":"175.13641ms","start":"2026-05-30T16:44:44.55002Z","end":"2026-05-30T16:44:44.725156Z","steps":["trace[1915027386] 'agreement among raft nodes before linearized reading' (duration: 174.648553ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:45:55.510331Z","caller":"traceutil/trace.go:171","msg":"trace[1645728859] linearizableReadLoop","detail":"{readStateIndex:2651; appliedIndex:2650; }","duration":"119.711603ms","start":"2026-05-30T16:45:55.390598Z","end":"2026-05-30T16:45:55.51031Z","steps":["trace[1645728859] 'read index received' (duration: 119.569101ms)","trace[1645728859] 'applied index is now lower than readState.Index' (duration: 141.182µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:45:55.510432Z","caller":"traceutil/trace.go:171","msg":"trace[1917401002] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"187.245252ms","start":"2026-05-30T16:45:55.323164Z","end":"2026-05-30T16:45:55.510409Z","steps":["trace[1917401002] 'process raft request' (duration: 187.02953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:45:55.510517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.901255ms","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-05-30T16:45:55.510548Z","caller":"traceutil/trace.go:171","msg":"trace[733964632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2551; }","duration":"119.985495ms","start":"2026-05-30T16:45:55.390554Z","end":"2026-05-30T16:45:55.510539Z","steps":["trace[733964632] 'agreement among raft nodes before linearized reading' (duration: 119.847964ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:46:28.574764Z","caller":"traceutil/trace.go:171","msg":"trace[1650892561] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"127.566145ms","start":"2026-05-30T16:46:28.447172Z","end":"2026-05-30T16:46:28.574738Z","steps":["trace[1650892561] 'process raft request' (duration: 76.144669ms)","trace[1650892561] 'compare' (duration: 50.893701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:50:23.502287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.861508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:50:23.502425Z","caller":"traceutil/trace.go:171","msg":"trace[1622575142] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4701; }","duration":"104.073382ms","start":"2026-05-30T16:50:23.398326Z","end":"2026-05-30T16:50:23.5024Z","steps":["trace[1622575142] 'count revisions from in-memory index tree' (duration: 103.729557ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:23.855636Z","caller":"traceutil/trace.go:171","msg":"trace[963037750] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"121.481465ms","start":"2026-05-30T16:50:23.734126Z","end":"2026-05-30T16:50:23.855607Z","steps":["trace[963037750] 'process raft request' (duration: 121.335822ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:23.928603Z","caller":"traceutil/trace.go:171","msg":"trace[103367571] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"153.617502ms","start":"2026-05-30T16:50:23.774952Z","end":"2026-05-30T16:50:23.928569Z","steps":["trace[103367571] 'process raft request' (duration: 153.382758ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:23.928638Z","caller":"traceutil/trace.go:171","msg":"trace[2029431045] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"153.545971ms","start":"2026-05-30T16:50:23.775067Z","end":"2026-05-30T16:50:23.928613Z","steps":["trace[2029431045] 'process raft request' (duration: 153.424759ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:23.928894Z","caller":"traceutil/trace.go:171","msg":"trace[1791334127] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"152.907879ms","start":"2026-05-30T16:50:23.775967Z","end":"2026-05-30T16:50:23.928875Z","steps":["trace[1791334127] 'process raft request' (duration: 152.578914ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:23.929041Z","caller":"traceutil/trace.go:171","msg":"trace[1013753517] linearizableReadLoop","detail":"{readStateIndex:4902; appliedIndex:4897; }","duration":"127.61654ms","start":"2026-05-30T16:50:23.801381Z","end":"2026-05-30T16:50:23.928998Z","steps":["trace[1013753517] 'read index received' (duration: 54.108877ms)","trace[1013753517] 'applied index is now lower than readState.Index' (duration: 73.506133ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:50:23.929093Z","caller":"traceutil/trace.go:171","msg":"trace[1998480120] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"151.947632ms","start":"2026-05-30T16:50:23.777108Z","end":"2026-05-30T16:50:23.929056Z","steps":["trace[1998480120] 'process raft request' (duration: 151.713808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:23.930194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.780222ms","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-05-30T16:50:23.930253Z","caller":"traceutil/trace.go:171","msg":"trace[774234489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4718; }","duration":"128.867024ms","start":"2026-05-30T16:50:23.801373Z","end":"2026-05-30T16:50:23.93024Z","steps":["trace[774234489] 'agreement among raft nodes before linearized reading' (duration: 127.801714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:24.002105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.87052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:50:24.002217Z","caller":"traceutil/trace.go:171","msg":"trace[2014097789] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:4720; }","duration":"141.043863ms","start":"2026-05-30T16:50:23.861151Z","end":"2026-05-30T16:50:24.002194Z","steps":["trace[2014097789] 'agreement among raft nodes before linearized reading' (duration: 140.81888ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.002234Z","caller":"traceutil/trace.go:171","msg":"trace[898327726] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"113.947643ms","start":"2026-05-30T16:50:23.888234Z","end":"2026-05-30T16:50:24.002181Z","steps":["trace[898327726] 'process raft request' (duration: 113.685669ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.003325Z","caller":"traceutil/trace.go:171","msg":"trace[479567493] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"118.612435ms","start":"2026-05-30T16:50:23.884686Z","end":"2026-05-30T16:50:24.003298Z","steps":["trace[479567493] 'process raft request' (duration: 117.025537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:24.260601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.28363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:50:24.26081Z","caller":"traceutil/trace.go:171","msg":"trace[1414804341] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"252.475523ms","start":"2026-05-30T16:50:24.008315Z","end":"2026-05-30T16:50:24.26079Z","steps":["trace[1414804341] 'process raft request' (duration: 252.412192ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.260861Z","caller":"traceutil/trace.go:171","msg":"trace[244811421] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"325.984276ms","start":"2026-05-30T16:50:23.934842Z","end":"2026-05-30T16:50:24.260827Z","steps":["trace[244811421] 'process raft request' (duration: 132.378451ms)","trace[244811421] 'compare' (duration: 193.148198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:50:24.261023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:50:23.934829Z","time spent":"326.107189ms","remote":"127.0.0.1:45982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T16:50:24.471346Z","caller":"traceutil/trace.go:171","msg":"trace[407692434] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"205.098594ms","start":"2026-05-30T16:50:24.266202Z","end":"2026-05-30T16:50:24.4713Z","steps":["trace[407692434] 'process raft request' (duration: 151.492208ms)","trace[407692434] 'compare' (duration: 53.414073ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:50:24.501488Z","caller":"traceutil/trace.go:171","msg":"trace[396627415] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"233.215711ms","start":"2026-05-30T16:50:24.268212Z","end":"2026-05-30T16:50:24.501428Z","steps":["trace[396627415] 'process raft request' (duration: 232.758002ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.501622Z","caller":"traceutil/trace.go:171","msg":"trace[639644016] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"232.238793ms","start":"2026-05-30T16:50:24.269315Z","end":"2026-05-30T16:50:24.501554Z","steps":["trace[639644016] 'process raft request' (duration: 231.761714ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.502032Z","caller":"traceutil/trace.go:171","msg":"trace[2019553951] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"235.52072ms","start":"2026-05-30T16:50:24.266489Z","end":"2026-05-30T16:50:24.502009Z","steps":["trace[2019553951] 'process raft request' (duration: 234.352089ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.625572Z","caller":"traceutil/trace.go:171","msg":"trace[2137034105] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"115.16397ms","start":"2026-05-30T16:50:24.510376Z","end":"2026-05-30T16:50:24.62554Z","steps":["trace[2137034105] 'process raft request' (duration: 115.053098ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.625801Z","caller":"traceutil/trace.go:171","msg":"trace[397491320] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"119.17406ms","start":"2026-05-30T16:50:24.506573Z","end":"2026-05-30T16:50:24.625747Z","steps":["trace[397491320] 'process raft request' (duration: 111.07927ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.626149Z","caller":"traceutil/trace.go:171","msg":"trace[1406016686] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"106.144445ms","start":"2026-05-30T16:50:24.519962Z","end":"2026-05-30T16:50:24.626106Z","steps":["trace[1406016686] 'process raft request' (duration: 105.951441ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.626387Z","caller":"traceutil/trace.go:171","msg":"trace[523769968] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"106.283446ms","start":"2026-05-30T16:50:24.520093Z","end":"2026-05-30T16:50:24.626376Z","steps":["trace[523769968] 'process raft request' (duration: 105.850729ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.626429Z","caller":"traceutil/trace.go:171","msg":"trace[1710119490] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"106.49326ms","start":"2026-05-30T16:50:24.519917Z","end":"2026-05-30T16:50:24.62641Z","steps":["trace[1710119490] 'process raft request' (duration: 105.944421ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:24.626578Z","caller":"traceutil/trace.go:171","msg":"trace[710847445] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"106.332408ms","start":"2026-05-30T16:50:24.520237Z","end":"2026-05-30T16:50:24.626569Z","steps":["trace[710847445] 'process raft request' (duration: 105.749728ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:30.796325Z","caller":"traceutil/trace.go:171","msg":"trace[1292915354] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"328.019817ms","start":"2026-05-30T16:50:30.468283Z","end":"2026-05-30T16:50:30.796302Z","steps":["trace[1292915354] 'read index received' (duration: 323.671853ms)","trace[1292915354] 'applied index is now lower than readState.Index' (duration: 4.347314ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:50:30.796432Z","caller":"traceutil/trace.go:171","msg":"trace[1281582621] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"331.240343ms","start":"2026-05-30T16:50:30.465181Z","end":"2026-05-30T16:50:30.796421Z","steps":["trace[1281582621] 'process raft request' (duration: 326.681095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:30.796502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:50:30.465165Z","time spent":"331.284444ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T16:50:30.796663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.341473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T16:50:30.796727Z","caller":"traceutil/trace.go:171","msg":"trace[929784877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4864; }","duration":"328.449974ms","start":"2026-05-30T16:50:30.468261Z","end":"2026-05-30T16:50:30.796711Z","steps":["trace[929784877] 'agreement among raft nodes before linearized reading' (duration: 328.237051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:30.796758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:50:30.468255Z","time spent":"328.498075ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-30T16:50:30.79676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.708532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-30T16:50:30.796801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.32877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:50:30.796831Z","caller":"traceutil/trace.go:171","msg":"trace[1358484339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4865; }","duration":"239.385071ms","start":"2026-05-30T16:50:30.557439Z","end":"2026-05-30T16:50:30.796824Z","steps":["trace[1358484339] 'agreement among raft nodes before linearized reading' (duration: 239.3386ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:30.796833Z","caller":"traceutil/trace.go:171","msg":"trace[1726855661] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"144.972794ms","start":"2026-05-30T16:50:30.651854Z","end":"2026-05-30T16:50:30.796827Z","steps":["trace[1726855661] 'process raft request' (duration: 144.7347ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:30.796805Z","caller":"traceutil/trace.go:171","msg":"trace[909070166] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4865; }","duration":"327.768213ms","start":"2026-05-30T16:50:30.469028Z","end":"2026-05-30T16:50:30.796797Z","steps":["trace[909070166] 'agreement among raft nodes before linearized reading' (duration: 327.62281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:30.796978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:50:30.469021Z","time spent":"327.949787ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-30T16:50:30.796955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.435611ms","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-05-30T16:50:30.797069Z","caller":"traceutil/trace.go:171","msg":"trace[836497225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4865; }","duration":"142.587324ms","start":"2026-05-30T16:50:30.654468Z","end":"2026-05-30T16:50:30.797055Z","steps":["trace[836497225] 'agreement among raft nodes before linearized reading' (duration: 142.190177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:30.798068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.261744ms","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-05-30T16:50:30.798176Z","caller":"traceutil/trace.go:171","msg":"trace[2085255337] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4865; }","duration":"160.405126ms","start":"2026-05-30T16:50:30.637741Z","end":"2026-05-30T16:50:30.798146Z","steps":["trace[2085255337] 'agreement among raft nodes before linearized reading' (duration: 158.964432ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:37.887304Z","caller":"traceutil/trace.go:171","msg":"trace[2041972517] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"102.98407ms","start":"2026-05-30T16:50:37.784303Z","end":"2026-05-30T16:50:37.887287Z","steps":["trace[2041972517] 'process raft request' (duration: 99.499142ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:51:57.07497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-05-30T16:51:57.173998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"97.995322ms","hash":443339438,"current-db-size-bytes":53596160,"current-db-size":"54 MB","current-db-size-in-use-bytes":29257728,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-30T16:51:57.174053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":443339438,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-05-30T16:56:57.080006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5589} {"level":"info","ts":"2026-05-30T16:56:57.15911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5589,"took":"77.88538ms","hash":331300984,"current-db-size-bytes":53596160,"current-db-size":"54 MB","current-db-size-in-use-bytes":18243584,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-30T16:56:57.159176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":331300984,"revision":5589,"compact-revision":2882}