{"level":"warn","ts":"2026-04-27T11:58:56.455991Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T11:58:56.456092Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.147:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.147:2380","--initial-cluster=instance=https://199.19.213.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.147: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-04-27T11:58:56.456199Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T11:58:56.456214Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-04-27T11:58:56.456243Z","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-04-27T11:58:56.4571Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"]} {"level":"info","ts":"2026-04-27T11:58:56.45721Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.147: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-04-27T11:58:56.678885Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"221.417171ms"} {"level":"info","ts":"2026-04-27T11:58:57.111647Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6244e483e416226f","cluster-id":"68a374eab3e2de92"} {"level":"info","ts":"2026-04-27T11:58:57.111942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T11:58:57.11206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 0"} {"level":"info","ts":"2026-04-27T11:58:57.112159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6244e483e416226f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T11:58:57.112237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became follower at term 1"} {"level":"info","ts":"2026-04-27T11:58:57.112432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"warn","ts":"2026-04-27T11:58:57.121429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T11:58:57.124766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T11:58:57.125755Z","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-04-27T11:58:57.127956Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6244e483e416226f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T11:58:57.128139Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6244e483e416226f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T11:58:57.128237Z","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-04-27T11:58:57.12828Z","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-04-27T11:58:57.12829Z","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-04-27T11:58:57.129089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f switched to configuration voters=(7081035769251766895)"} {"level":"info","ts":"2026-04-27T11:58:57.130249Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","added-peer-id":"6244e483e416226f","added-peer-peer-urls":["https://199.19.213.147:2380"]} {"level":"info","ts":"2026-04-27T11:58:57.1322Z","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-04-27T11:58:57.132364Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6244e483e416226f","initial-advertise-peer-urls":["https://199.19.213.147:2380"],"listen-peer-urls":["https://199.19.213.147:2380"],"advertise-client-urls":["https://199.19.213.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T11:58:57.132389Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T11:58:57.132452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-04-27T11:58:57.132464Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.147:2380"} {"level":"info","ts":"2026-04-27T11:58:57.21302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T11:58:57.213117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T11:58:57.21314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgPreVoteResp from 6244e483e416226f at term 1"} {"level":"info","ts":"2026-04-27T11:58:57.213161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became candidate at term 2"} {"level":"info","ts":"2026-04-27T11:58:57.213178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f received MsgVoteResp from 6244e483e416226f at term 2"} {"level":"info","ts":"2026-04-27T11:58:57.213194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6244e483e416226f became leader at term 2"} {"level":"info","ts":"2026-04-27T11:58:57.213213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6244e483e416226f elected leader 6244e483e416226f at term 2"} {"level":"info","ts":"2026-04-27T11:58:57.215178Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:57.215885Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6244e483e416226f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.147:2379]}","request-path":"/0/members/6244e483e416226f/attributes","cluster-id":"68a374eab3e2de92","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T11:58:57.216029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:58:57.21611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:58:57.216579Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T11:58:57.216667Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T11:58:57.217468Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"68a374eab3e2de92","local-member-id":"6244e483e416226f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:57.217573Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:57.217612Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:57.219788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T11:58:57.220278Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.147:2379"} {"level":"info","ts":"2026-04-27T11:59:32.840788Z","caller":"traceutil/trace.go:171","msg":"trace[900239483] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"309.676756ms","start":"2026-04-27T11:59:32.531087Z","end":"2026-04-27T11:59:32.840763Z","steps":["trace[900239483] 'process raft request' (duration: 309.438664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:32.841709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.531062Z","time spent":"309.954799ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:32.973945Z","caller":"traceutil/trace.go:171","msg":"trace[1271907339] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"298.829905ms","start":"2026-04-27T11:59:32.67509Z","end":"2026-04-27T11:59:32.97392Z","steps":["trace[1271907339] 'read index received' (duration: 165.520595ms)","trace[1271907339] 'applied index is now lower than readState.Index' (duration: 133.30834ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:32.974128Z","caller":"traceutil/trace.go:171","msg":"trace[819956766] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"412.723981ms","start":"2026-04-27T11:59:32.561293Z","end":"2026-04-27T11:59:32.974017Z","steps":["trace[819956766] 'process raft request' (duration: 411.493219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:32.974193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.501566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T11:59:32.974215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.978141ms","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-04-27T11:59:32.974292Z","caller":"traceutil/trace.go:171","msg":"trace[1204235919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"190.102422ms","start":"2026-04-27T11:59:32.784167Z","end":"2026-04-27T11:59:32.97427Z","steps":["trace[1204235919] 'agreement among raft nodes before linearized reading' (duration: 189.94856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:32.974126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.015958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:32.974339Z","caller":"traceutil/trace.go:171","msg":"trace[134167056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"299.28284ms","start":"2026-04-27T11:59:32.675042Z","end":"2026-04-27T11:59:32.974325Z","steps":["trace[134167056] 'agreement among raft nodes before linearized reading' (duration: 299.028038ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:32.974236Z","caller":"traceutil/trace.go:171","msg":"trace[1509593194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"188.615277ms","start":"2026-04-27T11:59:32.785611Z","end":"2026-04-27T11:59:32.974226Z","steps":["trace[1509593194] 'agreement among raft nodes before linearized reading' (duration: 188.517326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:32.974455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.561245Z","time spent":"413.017065ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:34.037876Z","caller":"traceutil/trace.go:171","msg":"trace[1453650508] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"404.549931ms","start":"2026-04-27T11:59:33.6333Z","end":"2026-04-27T11:59:34.03785Z","steps":["trace[1453650508] 'read index received' (duration: 403.888074ms)","trace[1453650508] 'applied index is now lower than readState.Index' (duration: 661.007µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:34.037952Z","caller":"traceutil/trace.go:171","msg":"trace[745074195] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"574.251801ms","start":"2026-04-27T11:59:33.463666Z","end":"2026-04-27T11:59:34.037918Z","steps":["trace[745074195] 'process raft request' (duration: 573.671586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:34.038157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:33.463636Z","time spent":"574.426393ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:34.038174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.862933ms","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-04-27T11:59:34.038222Z","caller":"traceutil/trace.go:171","msg":"trace[1145862818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"404.966655ms","start":"2026-04-27T11:59:33.633242Z","end":"2026-04-27T11:59:34.038209Z","steps":["trace[1145862818] 'agreement among raft nodes before linearized reading' (duration: 404.795093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:34.038206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.831944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:59:34.038271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:33.633221Z","time spent":"405.041066ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T11:59:34.038383Z","caller":"traceutil/trace.go:171","msg":"trace[1683707045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"360.981265ms","start":"2026-04-27T11:59:33.677306Z","end":"2026-04-27T11:59:34.038288Z","steps":["trace[1683707045] 'agreement among raft nodes before linearized reading' (duration: 360.837293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:34.038451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:33.677291Z","time spent":"361.126486ms","remote":"127.0.0.1:56650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:00:40.541985Z","caller":"traceutil/trace.go:171","msg":"trace[1368213300] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"137.817056ms","start":"2026-04-27T12:00:40.404135Z","end":"2026-04-27T12:00:40.541952Z","steps":["trace[1368213300] 'process raft request' (duration: 124.452386ms)","trace[1368213300] 'compare' (duration: 13.198196ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:47.554668Z","caller":"traceutil/trace.go:171","msg":"trace[1401415341] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"109.960766ms","start":"2026-04-27T12:00:47.444673Z","end":"2026-04-27T12:00:47.554634Z","steps":["trace[1401415341] 'process raft request' (duration: 109.767332ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:47.563458Z","caller":"traceutil/trace.go:171","msg":"trace[323398081] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"118.538217ms","start":"2026-04-27T12:00:47.444908Z","end":"2026-04-27T12:00:47.563446Z","steps":["trace[323398081] 'process raft request' (duration: 118.343313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:19.490856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.797405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:01:19.491059Z","caller":"traceutil/trace.go:171","msg":"trace[1717607836] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"298.004764ms","start":"2026-04-27T12:01:19.193017Z","end":"2026-04-27T12:01:19.491022Z","steps":["trace[1717607836] 'process raft request' (duration: 78.76739ms)","trace[1717607836] 'compare' (duration: 218.691433ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:19.661578Z","caller":"traceutil/trace.go:171","msg":"trace[1109475091] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"129.390545ms","start":"2026-04-27T12:01:19.532163Z","end":"2026-04-27T12:01:19.661553Z","steps":["trace[1109475091] 'process raft request' (duration: 122.357904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:19.885377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.681935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:01:19.885511Z","caller":"traceutil/trace.go:171","msg":"trace[138042580] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"178.87082ms","start":"2026-04-27T12:01:19.706616Z","end":"2026-04-27T12:01:19.885486Z","steps":["trace[138042580] 'process raft request' (duration: 74.999891ms)","trace[138042580] 'compare' (duration: 103.485252ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:33.684683Z","caller":"traceutil/trace.go:171","msg":"trace[359414781] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"144.657005ms","start":"2026-04-27T12:01:33.540003Z","end":"2026-04-27T12:01:33.68466Z","steps":["trace[359414781] 'process raft request' (duration: 144.525173ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:53.125764Z","caller":"traceutil/trace.go:171","msg":"trace[1545113010] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"323.070418ms","start":"2026-04-27T12:01:52.802671Z","end":"2026-04-27T12:01:53.125741Z","steps":["trace[1545113010] 'process raft request' (duration: 322.887216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:53.125916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:52.80265Z","time spent":"323.185471ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:53.395219Z","caller":"traceutil/trace.go:171","msg":"trace[1088318665] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2236; }","duration":"513.08383ms","start":"2026-04-27T12:01:52.882107Z","end":"2026-04-27T12:01:53.395191Z","steps":["trace[1088318665] 'read index received' (duration: 243.437433ms)","trace[1088318665] 'applied index is now lower than readState.Index' (duration: 269.645427ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:53.39543Z","caller":"traceutil/trace.go:171","msg":"trace[1856906434] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"569.55753ms","start":"2026-04-27T12:01:52.825863Z","end":"2026-04-27T12:01:53.395421Z","steps":["trace[1856906434] 'process raft request' (duration: 552.714425ms)","trace[1856906434] 'compare' (duration: 16.444049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:53.395506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:52.825846Z","time spent":"569.601811ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:01:53.395687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.579577ms","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-04-27T12:01:53.395706Z","caller":"traceutil/trace.go:171","msg":"trace[2095867047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2158; }","duration":"513.625328ms","start":"2026-04-27T12:01:52.882074Z","end":"2026-04-27T12:01:53.3957Z","steps":["trace[2095867047] 'agreement among raft nodes before linearized reading' (duration: 513.544806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:53.395722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:52.882059Z","time spent":"513.659138ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T12:01:53.395774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.042077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:53.39587Z","caller":"traceutil/trace.go:171","msg":"trace[941849344] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2158; }","duration":"199.189461ms","start":"2026-04-27T12:01:53.196656Z","end":"2026-04-27T12:01:53.395846Z","steps":["trace[941849344] 'agreement among raft nodes before linearized reading' (duration: 199.030707ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:54.273954Z","caller":"traceutil/trace.go:171","msg":"trace[266000169] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"140.580699ms","start":"2026-04-27T12:01:54.133355Z","end":"2026-04-27T12:01:54.273935Z","steps":["trace[266000169] 'process raft request' (duration: 140.458327ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:56.005601Z","caller":"traceutil/trace.go:171","msg":"trace[1685502191] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"177.137692ms","start":"2026-04-27T12:02:55.828399Z","end":"2026-04-27T12:02:56.005536Z","steps":["trace[1685502191] 'process raft request' (duration: 176.522745ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:03:13.076071Z","caller":"traceutil/trace.go:171","msg":"trace[969009070] linearizableReadLoop","detail":"{readStateIndex:2800; appliedIndex:2799; }","duration":"127.487543ms","start":"2026-04-27T12:03:12.948563Z","end":"2026-04-27T12:03:13.076051Z","steps":["trace[969009070] 'read index received' (duration: 127.30077ms)","trace[969009070] 'applied index is now lower than readState.Index' (duration: 184.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:03:13.076135Z","caller":"traceutil/trace.go:171","msg":"trace[1679701268] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"158.70216ms","start":"2026-04-27T12:03:12.917417Z","end":"2026-04-27T12:03:13.076119Z","steps":["trace[1679701268] 'process raft request' (duration: 158.500288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:13.076249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.667515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-27T12:03:13.076281Z","caller":"traceutil/trace.go:171","msg":"trace[2090637564] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2701; }","duration":"127.738135ms","start":"2026-04-27T12:03:12.948532Z","end":"2026-04-27T12:03:13.07627Z","steps":["trace[2090637564] 'agreement among raft nodes before linearized reading' (duration: 127.602914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.348755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.763466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58637c81-d97e-47ab-ba23-51311f4ef0b8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-27T12:07:24.348835Z","caller":"traceutil/trace.go:171","msg":"trace[1091119979] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58637c81-d97e-47ab-ba23-51311f4ef0b8; range_end:; response_count:1; response_revision:4781; }","duration":"144.901098ms","start":"2026-04-27T12:07:24.203919Z","end":"2026-04-27T12:07:24.34882Z","steps":["trace[1091119979] 'range keys from in-memory index tree' (duration: 144.500021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.348859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.401518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:24.348937Z","caller":"traceutil/trace.go:171","msg":"trace[29502826] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4781; }","duration":"119.574142ms","start":"2026-04-27T12:07:24.229345Z","end":"2026-04-27T12:07:24.348919Z","steps":["trace[29502826] 'count revisions from in-memory index tree' (duration: 119.317627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:28.39099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.891171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:28.391061Z","caller":"traceutil/trace.go:171","msg":"trace[1510048420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4845; }","duration":"150.020592ms","start":"2026-04-27T12:07:28.241026Z","end":"2026-04-27T12:07:28.391047Z","steps":["trace[1510048420] 'count revisions from in-memory index tree' (duration: 149.805578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:28.391329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.886157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-27T12:07:28.39142Z","caller":"traceutil/trace.go:171","msg":"trace[226981225] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4845; }","duration":"130.01177ms","start":"2026-04-27T12:07:28.261391Z","end":"2026-04-27T12:07:28.391403Z","steps":["trace[226981225] 'range keys from in-memory index tree' (duration: 129.716253ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:37.337157Z","caller":"traceutil/trace.go:171","msg":"trace[1058818973] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"193.080466ms","start":"2026-04-27T12:07:37.144056Z","end":"2026-04-27T12:07:37.337136Z","steps":["trace[1058818973] 'read index received' (duration: 192.869723ms)","trace[1058818973] 'applied index is now lower than readState.Index' (duration: 207.563µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:37.337348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.27952ms","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-04-27T12:07:37.337371Z","caller":"traceutil/trace.go:171","msg":"trace[2022238746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4955; }","duration":"193.349711ms","start":"2026-04-27T12:07:37.144013Z","end":"2026-04-27T12:07:37.337363Z","steps":["trace[2022238746] 'agreement among raft nodes before linearized reading' (duration: 193.203539ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:37.337647Z","caller":"traceutil/trace.go:171","msg":"trace[1820776029] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"194.548882ms","start":"2026-04-27T12:07:37.143092Z","end":"2026-04-27T12:07:37.337641Z","steps":["trace[1820776029] 'process raft request' (duration: 193.915811ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:57.248803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2932} {"level":"info","ts":"2026-04-27T12:08:57.326155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2932,"took":"75.933092ms","hash":3389861528,"current-db-size-bytes":53276672,"current-db-size":"53 MB","current-db-size-in-use-bytes":28721152,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-27T12:08:57.326259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3389861528,"revision":2932,"compact-revision":-1} {"level":"info","ts":"2026-04-27T12:11:16.989065Z","caller":"traceutil/trace.go:171","msg":"trace[2146494686] transaction","detail":"{read_only:false; response_revision:6568; number_of_response:1; }","duration":"114.621988ms","start":"2026-04-27T12:11:16.874414Z","end":"2026-04-27T12:11:16.989036Z","steps":["trace[2146494686] 'process raft request' (duration: 114.238892ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:11:17.826322Z","caller":"traceutil/trace.go:171","msg":"trace[966228703] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"104.786671ms","start":"2026-04-27T12:11:17.721492Z","end":"2026-04-27T12:11:17.826279Z","steps":["trace[966228703] 'process raft request' (duration: 36.404739ms)","trace[966228703] 'compare' (duration: 68.000676ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:11:34.511019Z","caller":"traceutil/trace.go:171","msg":"trace[840331571] transaction","detail":"{read_only:false; response_revision:6708; number_of_response:1; }","duration":"236.081082ms","start":"2026-04-27T12:11:34.274705Z","end":"2026-04-27T12:11:34.510787Z","steps":["trace[840331571] 'process raft request' (duration: 235.789338ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:13:57.254405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5606} {"level":"info","ts":"2026-04-27T12:13:57.330913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5606,"took":"74.791187ms","hash":4100288688,"current-db-size-bytes":53276672,"current-db-size":"53 MB","current-db-size-in-use-bytes":18186240,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-27T12:13:57.330976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4100288688,"revision":5606,"compact-revision":2932}